builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0077 starttime: 1495240660.85 results: success (0) revision: 979f11deabd01d6cb897d064d3d9ed11ea8baa93 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:40.851196) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:40.851662) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:40.851944) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:40.874914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:40.875209) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtXSW8jBkX/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-19 17:37:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.01M=0.001s 2017-05-19 17:37:41 (8.01 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.169630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:41.066588) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:41.066930) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtXSW8jBkX/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021328 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:41.115622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 17:37:41.116000) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 979f11deabd01d6cb897d064d3d9ed11ea8baa93 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 979f11deabd01d6cb897d064d3d9ed11ea8baa93 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtXSW8jBkX/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-19 17:37:41,191 truncating revision to first 12 chars 2017-05-19 17:37:41,192 Setting DEBUG logging. 2017-05-19 17:37:41,192 attempt 1/10 2017-05-19 17:37:41,192 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/979f11deabd0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 17:37:42,260 unpacking tar archive at: mozilla-central-979f11deabd0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.332170 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 17:37:42.469741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:42.470093) ========= script_repo_revision: 979f11deabd01d6cb897d064d3d9ed11ea8baa93 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:42.470504) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:42.470782) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 17:37:42.489096) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-19 17:37:42.489417) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtXSW8jBkX/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:37:42 INFO - MultiFileLogger online at 20170519 17:37:42 in /builds/slave/test 17:37:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:37:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:37:42 INFO - {'append_to_log': False, 17:37:42 INFO - 'base_work_dir': '/builds/slave/test', 17:37:42 INFO - 'blob_upload_branch': 'Firefox', 17:37:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:37:42 INFO - 'branch': 'Firefox', 17:37:42 INFO - 'buildbot_json_path': 'buildprops.json', 17:37:42 INFO - 'config_files': ('talos/mac_config.py',), 17:37:42 INFO - 'default_actions': ('clobber', 17:37:42 INFO - 'read-buildbot-config', 17:37:42 INFO - 'download-and-extract', 17:37:42 INFO - 'populate-webroot', 17:37:42 INFO - 'create-virtualenv', 17:37:42 INFO - 'install', 17:37:42 INFO - 'run-tests'), 17:37:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:37:42 INFO - 'download_minidump_stackwalk': True, 17:37:42 INFO - 'download_symbols': 'ondemand', 17:37:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:37:42 INFO - 'tooltool.py': '/tools/tooltool.py', 17:37:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:37:42 INFO - '/tools/misc-python/virtualenv.py')}, 17:37:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:37:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:37:42 INFO - 'gecko_profile': False, 17:37:42 INFO - 'gecko_profile_interval': 0, 17:37:42 INFO - 'installer_path': 'installer.exe', 17:37:42 INFO - 'log_level': 'info', 17:37:42 INFO - 'log_name': 'talos', 17:37:42 INFO - 'log_to_console': True, 17:37:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:37:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:37:42 INFO - 'opt_config_files': (), 17:37:42 INFO - 'pip_index': False, 17:37:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:37:42 INFO - 'cmd': ('bash', 17:37:42 INFO - '-c', 17:37:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:37:42 INFO - 'enabled': True, 17:37:42 INFO - 'halt_on_failure': False, 17:37:42 INFO - 'name': 'check_screen_resolution'},), 17:37:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:37:42 INFO - 'cmd': ('bash', 17:37:42 INFO - '-c', 17:37:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:37:42 INFO - 'enabled': True, 17:37:42 INFO - 'halt_on_failure': False, 17:37:42 INFO - 'name': 'check_screen_resolution'},), 17:37:42 INFO - 'run_cmd_checks_enabled': True, 17:37:42 INFO - 'suite': 'dromaeojs-e10s', 17:37:42 INFO - 'system_bits': '32', 17:37:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:37:42 INFO - 'title': 't-yosemite-r7-0077', 17:37:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:37:42 INFO - 'use_talos_json': True, 17:37:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:37:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:37:42 INFO - 'work_dir': 'build'} 17:37:42 INFO - [mozharness: 2017-05-20 00:37:42.697652Z] Running clobber step. 17:37:42 INFO - Running pre-action listener: _resource_record_pre_action 17:37:42 INFO - Running main action method: clobber 17:37:42 INFO - rmtree: /builds/slave/test/build 17:37:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:37:44 INFO - Running post-action listener: _resource_record_post_action 17:37:44 INFO - [mozharness: 2017-05-20 00:37:44.927621Z] Finished clobber step (success) 17:37:44 INFO - [mozharness: 2017-05-20 00:37:44.927743Z] Running read-buildbot-config step. 17:37:44 INFO - Running pre-action listener: _resource_record_pre_action 17:37:44 INFO - Running main action method: read_buildbot_config 17:37:44 INFO - Using buildbot properties: 17:37:44 INFO - { 17:37:44 INFO - "project": "", 17:37:44 INFO - "product": "firefox", 17:37:44 INFO - "who": "kwierso@gmail.com", 17:37:44 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:37:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:37:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:37:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:37:44 INFO - "stage_platform": "macosx64", 17:37:44 INFO - "basedir": "/builds/slave/test", 17:37:44 INFO - "buildnumber": 77, 17:37:44 INFO - "platform": "yosemite_r7", 17:37:44 INFO - "parent_task_id": "ItzSNHnESKixSdmZVb021w", 17:37:44 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:37:44 INFO - "slavebuilddir": "test", 17:37:44 INFO - "taskId": "IEI_fTGxQcSCEMzyZqY1Aw", 17:37:44 INFO - "branch": "mozilla-central", 17:37:44 INFO - "script_repo_revision": "production", 17:37:44 INFO - "revision": "979f11deabd01d6cb897d064d3d9ed11ea8baa93", 17:37:44 INFO - "slavename": "t-yosemite-r7-0077", 17:37:44 INFO - "repo_path": "mozilla-central" 17:37:44 INFO - } 17:37:44 INFO - Finding installer, test and symbols from parent task. 17:37:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IEI_fTGxQcSCEMzyZqY1Aw'}, attempt #1 17:37:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IEI_fTGxQcSCEMzyZqY1Aw'}, attempt #1 17:37:46 INFO - Task dependencies: J6se7cXyS0yUBahEcbM9RQ ItzSNHnESKixSdmZVb021w 17:37:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w'}, attempt #1 17:37:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:37:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/test_packages.json 17:37:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/target.crashreporter-symbols.zip 17:37:46 INFO - Running post-action listener: _resource_record_post_action 17:37:46 INFO - [mozharness: 2017-05-20 00:37:46.687399Z] Finished read-buildbot-config step (success) 17:37:46 INFO - [mozharness: 2017-05-20 00:37:46.687516Z] Running download-and-extract step. 17:37:46 INFO - Running pre-action listener: _resource_record_pre_action 17:37:46 INFO - Running main action method: download_and_extract 17:37:46 INFO - mkdir: /builds/slave/test/build/tests 17:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:37:46 INFO - trying https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:37:46 INFO - Downloading https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:37:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:37:47 INFO - Downloaded 1325 bytes. 17:37:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:37:47 INFO - Using the following test package requirements: 17:37:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:37:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:37:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:37:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:37:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'jsshell-mac.zip'], 17:37:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:37:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:37:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:37:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:37:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:37:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:37:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:37:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:37:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:37:47 INFO - Fetch https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:37:49 INFO - Content-Length response header: 57622958 17:37:49 INFO - Bytes received: 57622958 17:38:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:38:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:38:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:38:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:38:10 INFO - Content-Length response header: 57622958 17:38:10 INFO - Bytes received: 57622958 17:38:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:38:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:38:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:38:28 INFO - Content-Length response header: 14410000 17:38:28 INFO - Bytes received: 14410000 17:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:28 INFO - trying https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:38:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:38:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:38:30 INFO - Downloaded 68044363 bytes. 17:38:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:38:30 INFO - mkdir: /builds/slave/test/properties 17:38:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:38:30 INFO - Writing to file /builds/slave/test/properties/build_url 17:38:30 INFO - Contents: 17:38:30 INFO - build_url:https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:38:30 INFO - Running post-action listener: _resource_record_post_action 17:38:30 INFO - Running post-action listener: set_extra_try_arguments 17:38:30 INFO - [mozharness: 2017-05-20 00:38:30.800149Z] Finished download-and-extract step (success) 17:38:30 INFO - [mozharness: 2017-05-20 00:38:30.800290Z] Running populate-webroot step. 17:38:30 INFO - Running pre-action listener: _resource_record_pre_action 17:38:30 INFO - Running main action method: populate_webroot 17:38:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:38:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:38:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:38:30 INFO - '--tppagecycles', 17:38:30 INFO - '7'], 17:38:30 INFO - 'tests': ['tsvgm']}}, 17:38:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:38:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:38:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:38:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:38:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['damp', 'tps']}, 17:38:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'tests': ['damp', 'tps']}, 17:38:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['dromaeo_dom']}, 17:38:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:38:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:38:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:38:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'tests': ['ts_paint', 'tp5o']}, 17:38:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['a11yr', 17:38:30 INFO - 'ts_paint', 17:38:30 INFO - 'tpaint', 17:38:30 INFO - 'sessionrestore', 17:38:30 INFO - 'sessionrestore_no_auto_restore', 17:38:30 INFO - 'tabpaint']}, 17:38:30 INFO - 'other-e10s': {'tests': ['a11yr', 17:38:30 INFO - 'ts_paint', 17:38:30 INFO - 'tpaint', 17:38:30 INFO - 'sessionrestore', 17:38:30 INFO - 'sessionrestore_no_auto_restore', 17:38:30 INFO - 'tabpaint']}, 17:38:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:38:30 INFO - 'ts_paint', 17:38:30 INFO - 'tpaint', 17:38:30 INFO - 'sessionrestore', 17:38:30 INFO - 'sessionrestore_no_auto_restore', 17:38:30 INFO - 'tabpaint']}, 17:38:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:38:30 INFO - 'ts_paint', 17:38:30 INFO - 'tpaint', 17:38:30 INFO - 'sessionrestore', 17:38:30 INFO - 'sessionrestore_no_auto_restore', 17:38:30 INFO - 'tabpaint']}, 17:38:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['a11yr', 17:38:30 INFO - 'ts_paint', 17:38:30 INFO - 'tpaint', 17:38:30 INFO - 'sessionrestore', 17:38:30 INFO - 'sessionrestore_no_auto_restore', 17:38:30 INFO - 'tabpaint']}, 17:38:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['a11yr', 17:38:30 INFO - 'ts_paint', 17:38:30 INFO - 'tpaint', 17:38:30 INFO - 'sessionrestore', 17:38:30 INFO - 'sessionrestore_no_auto_restore', 17:38:30 INFO - 'tabpaint']}, 17:38:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:38:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:38:30 INFO - 'bloom_basic_ref']}, 17:38:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['tsvgx', 17:38:30 INFO - 'tsvgr_opacity', 17:38:30 INFO - 'tart', 17:38:30 INFO - 'tscrollx', 17:38:30 INFO - 'cart', 17:38:30 INFO - 'tsvg_static']}, 17:38:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:38:30 INFO - 'tsvgr_opacity', 17:38:30 INFO - 'tart', 17:38:30 INFO - 'tscrollx', 17:38:30 INFO - 'cart', 17:38:30 INFO - 'tsvg_static']}, 17:38:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'talos_options': ['--disable-e10s'], 17:38:30 INFO - 'tests': ['tp5o']}, 17:38:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:38:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'talos_options': ['--disable-e10s', 17:38:30 INFO - '--xperf_path', 17:38:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:30 INFO - 'tests': ['tp5n']}, 17:38:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:38:30 INFO - 'talos_options': ['--xperf_path', 17:38:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:30 INFO - 'tests': ['tp5n']}}, 17:38:30 INFO - 'talos.zip': {'path': '', 17:38:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:38:30 INFO - Running post-action listener: _resource_record_post_action 17:38:30 INFO - [mozharness: 2017-05-20 00:38:30.821638Z] Finished populate-webroot step (success) 17:38:30 INFO - [mozharness: 2017-05-20 00:38:30.821815Z] Running create-virtualenv step. 17:38:30 INFO - Running pre-action listener: _resource_record_pre_action 17:38:30 INFO - Running main action method: create_virtualenv 17:38:30 INFO - Creating virtualenv /builds/slave/test/build/venv 17:38:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:38:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:38:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:38:30 INFO - Using real prefix '/tools/python27' 17:38:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:38:31 INFO - Installing distribute.............................................................................................................................................................................................done. 17:38:34 INFO - Installing pip.................done. 17:38:34 INFO - Return code: 0 17:38:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:38:34 INFO - Reading from file tmpfile_stdout 17:38:34 INFO - Output received: 17:38:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:38:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:34 INFO - 'HOME': '/Users/cltbld', 17:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:34 INFO - 'LOGNAME': 'cltbld', 17:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:34 INFO - 'MOZ_NO_REMOTE': '1', 17:38:34 INFO - 'NO_EM_RESTART': '1', 17:38:34 INFO - 'PAGER': '/bin/cat', 17:38:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:34 INFO - 'PWD': '/builds/slave/test', 17:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:34 INFO - 'SHELL': '/bin/bash', 17:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:34 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:34 INFO - 'USER': 'cltbld', 17:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:34 INFO - 'XPC_FLAGS': '0x0', 17:38:34 INFO - 'XPC_SERVICE_NAME': '0', 17:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:38:35 INFO - Cleaning up... 17:38:35 INFO - Return code: 0 17:38:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:35 INFO - 'HOME': '/Users/cltbld', 17:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:35 INFO - 'LOGNAME': 'cltbld', 17:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:35 INFO - 'MOZ_NO_REMOTE': '1', 17:38:35 INFO - 'NO_EM_RESTART': '1', 17:38:35 INFO - 'PAGER': '/bin/cat', 17:38:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:35 INFO - 'PWD': '/builds/slave/test', 17:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:35 INFO - 'SHELL': '/bin/bash', 17:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:35 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:35 INFO - 'USER': 'cltbld', 17:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:35 INFO - 'XPC_FLAGS': '0x0', 17:38:35 INFO - 'XPC_SERVICE_NAME': '0', 17:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:35 INFO - Downloading/unpacking psutil>=3.1.1 17:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:38:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:37 INFO - Installing collected packages: psutil 17:38:37 INFO - Running setup.py install for psutil 17:38:37 INFO - building 'psutil._psutil_osx' extension 17:38:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:38:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:38:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:38:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:38:37 INFO - building 'psutil._psutil_posix' extension 17:38:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:38:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:38:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:38:37 INFO - ^ 17:38:37 INFO - 1 warning generated. 17:38:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:38:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:38 INFO - Successfully installed psutil 17:38:38 INFO - Cleaning up... 17:38:38 INFO - Return code: 0 17:38:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:38:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:38:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:38 INFO - 'HOME': '/Users/cltbld', 17:38:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:38 INFO - 'LOGNAME': 'cltbld', 17:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:38 INFO - 'MOZ_NO_REMOTE': '1', 17:38:38 INFO - 'NO_EM_RESTART': '1', 17:38:38 INFO - 'PAGER': '/bin/cat', 17:38:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:38 INFO - 'PWD': '/builds/slave/test', 17:38:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:38 INFO - 'SHELL': '/bin/bash', 17:38:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:38 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:38 INFO - 'USER': 'cltbld', 17:38:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:38 INFO - 'XPC_FLAGS': '0x0', 17:38:38 INFO - 'XPC_SERVICE_NAME': '0', 17:38:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:38:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:38:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:38:41 INFO - Installing collected packages: mozsystemmonitor 17:38:41 INFO - Running setup.py install for mozsystemmonitor 17:38:41 INFO - Successfully installed mozsystemmonitor 17:38:41 INFO - Cleaning up... 17:38:41 INFO - Return code: 0 17:38:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:38:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:41 INFO - 'HOME': '/Users/cltbld', 17:38:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:41 INFO - 'LOGNAME': 'cltbld', 17:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:41 INFO - 'MOZ_NO_REMOTE': '1', 17:38:41 INFO - 'NO_EM_RESTART': '1', 17:38:41 INFO - 'PAGER': '/bin/cat', 17:38:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:41 INFO - 'PWD': '/builds/slave/test', 17:38:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:41 INFO - 'SHELL': '/bin/bash', 17:38:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:41 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:41 INFO - 'USER': 'cltbld', 17:38:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:41 INFO - 'XPC_FLAGS': '0x0', 17:38:41 INFO - 'XPC_SERVICE_NAME': '0', 17:38:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:41 INFO - Downloading/unpacking jsonschema==2.5.1 17:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:38:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:43 INFO - Downloading functools32-3.2.3-2.tar.gz 17:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:38:43 INFO - warning: no files found matching '*.txt' 17:38:43 INFO - no previously-included directories found matching 'build' 17:38:43 INFO - no previously-included directories found matching 'dist' 17:38:43 INFO - no previously-included directories found matching '.git*' 17:38:43 INFO - Installing collected packages: jsonschema, functools32 17:38:43 INFO - Running setup.py install for functools32 17:38:43 INFO - warning: no files found matching '*.txt' 17:38:43 INFO - no previously-included directories found matching 'build' 17:38:43 INFO - no previously-included directories found matching 'dist' 17:38:43 INFO - no previously-included directories found matching '.git*' 17:38:43 INFO - Successfully installed jsonschema functools32 17:38:43 INFO - Cleaning up... 17:38:43 INFO - Return code: 0 17:38:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:38:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:43 INFO - 'HOME': '/Users/cltbld', 17:38:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:43 INFO - 'LOGNAME': 'cltbld', 17:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:43 INFO - 'MOZ_NO_REMOTE': '1', 17:38:43 INFO - 'NO_EM_RESTART': '1', 17:38:43 INFO - 'PAGER': '/bin/cat', 17:38:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:43 INFO - 'PWD': '/builds/slave/test', 17:38:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:43 INFO - 'SHELL': '/bin/bash', 17:38:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:43 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:43 INFO - 'USER': 'cltbld', 17:38:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:43 INFO - 'XPC_FLAGS': '0x0', 17:38:43 INFO - 'XPC_SERVICE_NAME': '0', 17:38:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:38:43 INFO - Cleaning up... 17:38:43 INFO - Return code: 0 17:38:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:38:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:43 INFO - 'HOME': '/Users/cltbld', 17:38:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:43 INFO - 'LOGNAME': 'cltbld', 17:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:43 INFO - 'MOZ_NO_REMOTE': '1', 17:38:43 INFO - 'NO_EM_RESTART': '1', 17:38:43 INFO - 'PAGER': '/bin/cat', 17:38:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:43 INFO - 'PWD': '/builds/slave/test', 17:38:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:43 INFO - 'SHELL': '/bin/bash', 17:38:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:43 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:43 INFO - 'USER': 'cltbld', 17:38:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:43 INFO - 'XPC_FLAGS': '0x0', 17:38:43 INFO - 'XPC_SERVICE_NAME': '0', 17:38:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:43 INFO - Downloading/unpacking blobuploader==1.2.4 17:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:45 INFO - Downloading blobuploader-1.2.4.tar.gz 17:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:38:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:38:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:46 INFO - Downloading docopt-0.6.1.tar.gz 17:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:38:46 INFO - Installing collected packages: blobuploader, requests, docopt 17:38:46 INFO - Running setup.py install for blobuploader 17:38:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:38:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:38:46 INFO - Running setup.py install for requests 17:38:46 INFO - Running setup.py install for docopt 17:38:47 INFO - Successfully installed blobuploader requests docopt 17:38:47 INFO - Cleaning up... 17:38:47 INFO - Return code: 0 17:38:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:47 INFO - 'HOME': '/Users/cltbld', 17:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:47 INFO - 'LOGNAME': 'cltbld', 17:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:47 INFO - 'MOZ_NO_REMOTE': '1', 17:38:47 INFO - 'NO_EM_RESTART': '1', 17:38:47 INFO - 'PAGER': '/bin/cat', 17:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:47 INFO - 'PWD': '/builds/slave/test', 17:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:47 INFO - 'SHELL': '/bin/bash', 17:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:47 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:47 INFO - 'USER': 'cltbld', 17:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:47 INFO - 'XPC_FLAGS': '0x0', 17:38:47 INFO - 'XPC_SERVICE_NAME': '0', 17:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-cY0seu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-sc8Bc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-eiDugT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-cyLEPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-aMtQ7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-oifj0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Cq9was-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uehOJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BNK9I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-SuWYfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1Wd52B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-m9QVsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-H80H_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-837YXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-noy8ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-FE2Vy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-bYNe53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:38:49 INFO - Running setup.py install for manifestparser 17:38:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:38:49 INFO - Running setup.py install for mozcrash 17:38:49 INFO - Running setup.py install for mozdebug 17:38:49 INFO - Running setup.py install for mozdevice 17:38:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:38:49 INFO - Running setup.py install for mozfile 17:38:49 INFO - Running setup.py install for mozhttpd 17:38:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Running setup.py install for mozinfo 17:38:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Running setup.py install for mozInstall 17:38:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Running setup.py install for mozleak 17:38:50 INFO - Running setup.py install for mozlog 17:38:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Running setup.py install for moznetwork 17:38:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:38:50 INFO - Running setup.py install for mozprocess 17:38:50 INFO - Running setup.py install for mozprofile 17:38:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:38:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:38:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:38:51 INFO - Running setup.py install for mozrunner 17:38:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:38:51 INFO - Running setup.py install for mozscreenshot 17:38:51 INFO - Running setup.py install for moztest 17:38:51 INFO - Running setup.py install for mozversion 17:38:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:38:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:38:51 INFO - Cleaning up... 17:38:51 INFO - Return code: 0 17:38:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:38:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:51 INFO - 'HOME': '/Users/cltbld', 17:38:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:51 INFO - 'LOGNAME': 'cltbld', 17:38:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:51 INFO - 'MOZ_NO_REMOTE': '1', 17:38:51 INFO - 'NO_EM_RESTART': '1', 17:38:51 INFO - 'PAGER': '/bin/cat', 17:38:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:51 INFO - 'PWD': '/builds/slave/test', 17:38:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:51 INFO - 'SHELL': '/bin/bash', 17:38:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:51 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:51 INFO - 'USER': 'cltbld', 17:38:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:51 INFO - 'XPC_FLAGS': '0x0', 17:38:51 INFO - 'XPC_SERVICE_NAME': '0', 17:38:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-pEzW0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-qvmY8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Pwajug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-HKaG5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Nty1HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-HVuMb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5gI0zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-n9JhVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-pqYTqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-PKTqKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5s6rD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-dJoqFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1LKAPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-MuYO5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ZYZG0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-L21Kqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:53 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2tQ08L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:38:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:55 INFO - Downloading blessings-1.6.tar.gz 17:38:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:38:55 INFO - Installing collected packages: blessings 17:38:55 INFO - Running setup.py install for blessings 17:38:55 INFO - Successfully installed blessings 17:38:55 INFO - Cleaning up... 17:38:55 INFO - Return code: 0 17:38:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:55 INFO - Reading from file tmpfile_stdout 17:38:55 INFO - Current package versions: 17:38:55 INFO - blessings == 1.6 17:38:55 INFO - blobuploader == 1.2.4 17:38:55 INFO - docopt == 0.6.1 17:38:55 INFO - functools32 == 3.2.3-2 17:38:55 INFO - jsonschema == 2.5.1 17:38:55 INFO - manifestparser == 1.1 17:38:55 INFO - mozInstall == 1.12 17:38:55 INFO - mozcrash == 1.0 17:38:55 INFO - mozdebug == 0.1 17:38:55 INFO - mozdevice == 0.50 17:38:55 INFO - mozfile == 1.2 17:38:55 INFO - mozhttpd == 0.7 17:38:55 INFO - mozinfo == 0.9 17:38:55 INFO - mozleak == 0.1 17:38:55 INFO - mozlog == 3.4 17:38:55 INFO - moznetwork == 0.27 17:38:55 INFO - mozprocess == 0.25 17:38:55 INFO - mozprofile == 0.28 17:38:55 INFO - mozrunner == 6.13 17:38:55 INFO - mozscreenshot == 0.1 17:38:55 INFO - mozsystemmonitor == 0.3 17:38:55 INFO - moztest == 0.8 17:38:55 INFO - mozversion == 1.4 17:38:55 INFO - psutil == 3.1.1 17:38:55 INFO - requests == 1.2.3 17:38:55 INFO - wsgiref == 0.1.2 17:38:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:55 INFO - 'HOME': '/Users/cltbld', 17:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:55 INFO - 'LOGNAME': 'cltbld', 17:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:55 INFO - 'MOZ_NO_REMOTE': '1', 17:38:55 INFO - 'NO_EM_RESTART': '1', 17:38:55 INFO - 'PAGER': '/bin/cat', 17:38:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:55 INFO - 'PWD': '/builds/slave/test', 17:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:55 INFO - 'SHELL': '/bin/bash', 17:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:55 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:55 INFO - 'USER': 'cltbld', 17:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:55 INFO - 'XPC_FLAGS': '0x0', 17:38:55 INFO - 'XPC_SERVICE_NAME': '0', 17:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:38:56 INFO - Cleaning up... 17:38:56 INFO - Return code: 0 17:38:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c052b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c056768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d9bd89820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:56 INFO - 'HOME': '/Users/cltbld', 17:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:56 INFO - 'LOGNAME': 'cltbld', 17:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:56 INFO - 'MOZ_NO_REMOTE': '1', 17:38:56 INFO - 'NO_EM_RESTART': '1', 17:38:56 INFO - 'PAGER': '/bin/cat', 17:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:56 INFO - 'PWD': '/builds/slave/test', 17:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:56 INFO - 'SHELL': '/bin/bash', 17:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:38:56 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:56 INFO - 'USER': 'cltbld', 17:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:56 INFO - 'XPC_FLAGS': '0x0', 17:38:56 INFO - 'XPC_SERVICE_NAME': '0', 17:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:38:56 INFO - Cleaning up... 17:38:56 INFO - Return code: 0 17:38:56 INFO - Running post-action listener: _resource_record_post_action 17:38:56 INFO - Running post-action listener: _start_resource_monitoring 17:38:56 INFO - Starting resource monitoring. 17:38:56 INFO - [mozharness: 2017-05-20 00:38:56.390006Z] Finished create-virtualenv step (success) 17:38:56 INFO - [mozharness: 2017-05-20 00:38:56.391000Z] Running install step. 17:38:56 INFO - Running pre-action listener: _resource_record_pre_action 17:38:56 INFO - Running main action method: install 17:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:56 INFO - Reading from file tmpfile_stdout 17:38:56 INFO - Detecting whether we're running mozinstall >=1.0... 17:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:38:56 INFO - Reading from file tmpfile_stdout 17:38:56 INFO - Output received: 17:38:56 INFO - Usage: mozinstall [options] installer 17:38:56 INFO - Options: 17:38:56 INFO - -h, --help show this help message and exit 17:38:56 INFO - -d DEST, --destination=DEST 17:38:56 INFO - Directory to install application into. [default: 17:38:56 INFO - "/builds/slave/test"] 17:38:56 INFO - --app=APP Application being installed. [default: firefox] 17:38:56 INFO - mkdir: /builds/slave/test/build/application 17:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:39:17 INFO - Reading from file tmpfile_stdout 17:39:17 INFO - Output received: 17:39:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:39:17 INFO - Running post-action listener: _resource_record_post_action 17:39:17 INFO - [mozharness: 2017-05-20 00:39:17.904658Z] Finished install step (success) 17:39:17 INFO - [mozharness: 2017-05-20 00:39:17.904829Z] Running run-tests step. 17:39:17 INFO - Running pre-action listener: _resource_record_pre_action 17:39:17 INFO - Running main action method: run_tests 17:39:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:39:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:39:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:39:17 INFO - 2017-05-19 17:39:17.917 screenresolution[1895:15054] starting screenresolution argv=screenresolution get 17:39:17 INFO - 2017-05-19 17:39:17.920 screenresolution[1895:15054] Display 0: 1600x1200x32@60 17:39:17 INFO - 2017-05-19 17:39:17.925 screenresolution[1896:15057] starting screenresolution argv=screenresolution list 17:39:17 INFO - Available Modes on Display 0 17:39:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:39:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:39:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:39:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:39:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:39:18 INFO - Intel Iris: 17:39:18 INFO - Chipset Model: Intel Iris 17:39:18 INFO - Type: GPU 17:39:18 INFO - Bus: Built-In 17:39:18 INFO - VRAM (Dynamic, Max): 1536 MB 17:39:18 INFO - Vendor: Intel (0x8086) 17:39:18 INFO - Device ID: 0x0a2e 17:39:18 INFO - Revision ID: 0x0009 17:39:18 INFO - Displays: 17:39:18 INFO - Display: 17:39:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:39:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:39:18 INFO - Display Serial Number: 1600x1200 60 17:39:18 INFO - Main Display: Yes 17:39:18 INFO - Mirror: Off 17:39:18 INFO - Online: Yes 17:39:18 INFO - Rotation: Supported 17:39:18 INFO - Return code: 0 17:39:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:39:18 INFO - Python 2.7.3 17:39:18 INFO - Return code: 0 17:39:18 INFO - grabbing minidump binary from tooltool 17:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd62d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd57be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:39:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:39:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:39:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:39:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:39:18 INFO - Return code: 0 17:39:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:39:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:39:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:39:18 INFO - ENV: RUST_BACKTRACE is now 1 17:39:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:39:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:39:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:39:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtXSW8jBkX/Render', 17:39:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:18 INFO - 'HOME': '/Users/cltbld', 17:39:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:18 INFO - 'LOGNAME': 'cltbld', 17:39:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:39:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:18 INFO - 'MOZ_NO_REMOTE': '1', 17:39:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:39:18 INFO - 'NO_EM_RESTART': '1', 17:39:18 INFO - 'PAGER': '/bin/cat', 17:39:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:18 INFO - 'PWD': '/builds/slave/test', 17:39:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:39:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:18 INFO - 'RUST_BACKTRACE': '1', 17:39:18 INFO - 'SHELL': '/bin/bash', 17:39:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners', 17:39:18 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 17:39:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:18 INFO - 'USER': 'cltbld', 17:39:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:18 INFO - 'XPC_FLAGS': '0x0', 17:39:18 INFO - 'XPC_SERVICE_NAME': '0', 17:39:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:39:18 INFO - mozversion application_buildid: 20170519145243 17:39:18 INFO - mozversion application_changeset: 979f11deabd01d6cb897d064d3d9ed11ea8baa93 17:39:18 INFO - mozversion application_display_name: Nightly 17:39:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:39:18 INFO - mozversion application_name: Firefox 17:39:18 INFO - mozversion application_remotingname: firefox 17:39:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:39:18 INFO - mozversion application_vendor: Mozilla 17:39:18 INFO - mozversion application_version: 55.0a1 17:39:18 INFO - mozversion platform_buildid: 20170519145243 17:39:18 INFO - mozversion platform_changeset: 979f11deabd01d6cb897d064d3d9ed11ea8baa93 17:39:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:39:18 INFO - mozversion platform_version: 55.0a1 17:39:18 INFO - using testdate: 1495240758 17:39:18 INFO - actual date: 1495240758 17:39:18 INFO - starting webserver on 'localhost:49356' 17:39:18 INFO - SUITE-START | Running 2 tests 17:39:18 INFO - TEST-START | dromaeo_css 17:39:18 INFO - operating with platform_type : mac_ 17:39:18 INFO - Initialising browser for dromaeo_css test... 17:39:18 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpsbrsGn/profile http://localhost:49356/getInfo.html) 17:39:19 INFO - PID 1906 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2963: String.localeCompare is deprecated; use String.prototype.localeCompare instead 17:39:21 INFO - PID 1906 | 2017-05-19 17:39:21.045 plugin-container[1908:15217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e4b, name = 'com.apple.tsm.portname' 17:39:21 INFO - PID 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:21 INFO - PID 1906 | 2017-05-19 17:39:21.048 plugin-container[1908:15217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 17:39:21 INFO - PID 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:21 INFO - PID 1906 | 2017-05-19 17:39:21.048 plugin-container[1908:15217] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:21 INFO - PID 1906 | __metrics Screen width/height:1600/1200 17:39:21 INFO - PID 1906 | colorDepth:24 17:39:21 INFO - PID 1906 | Browser inner width/height: 1024/691 17:39:21 INFO - PID 1906 | __metrics 17:39:22 INFO - TEST-INFO | 1906: exit 0 17:39:22 INFO - Browser initialized. 17:39:22 INFO - Running cycle 1/1 for dromaeo_css test... 17:39:22 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpsbrsGn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:24 INFO - PID 1910 | 2017-05-19 17:39:24.426 plugin-container[1911:15369] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 17:39:24 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:24 INFO - PID 1910 | 2017-05-19 17:39:24.427 plugin-container[1911:15369] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 17:39:24 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:24 INFO - PID 1910 | 2017-05-19 17:39:24.427 plugin-container[1911:15369] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:25 INFO - PID 1910 | 2017-05-19 17:39:25.057 plugin-container[1912:15416] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 17:39:25 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:25 INFO - PID 1910 | 2017-05-19 17:39:25.058 plugin-container[1912:15416] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c03, name = 'com.apple.CFPasteboardClient' 17:39:25 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:25 INFO - PID 1910 | 2017-05-19 17:39:25.058 plugin-container[1912:15416] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:34 INFO - PID 1910 | RSS: Main: 242425856 17:39:34 INFO - PID 1910 | 17:42:37 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49356/tests/dromaeo/cssquery-ext.html) 17:42:38 INFO - PID 1910 | RSS: Main: 261160960 17:42:38 INFO - PID 1910 | 17:45:40 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-ext.html (next: http://localhost:49356/tests/dromaeo/cssquery-jquery.html) 17:45:41 INFO - PID 1910 | RSS: Main: 270802944 17:45:41 INFO - PID 1910 | 17:48:42 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49356/tests/dromaeo/cssquery-mootools.html) 17:48:43 INFO - PID 1910 | RSS: Main: 275156992 17:48:43 INFO - PID 1910 | 17:51:45 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49356/tests/dromaeo/cssquery-prototype.html) 17:51:45 INFO - PID 1910 | RSS: Main: 272834560 17:51:45 INFO - PID 1910 | 17:54:48 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49356/tests/dromaeo/cssquery-yui.html) 17:54:49 INFO - PID 1910 | RSS: Main: 274382848 17:54:49 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-yui.html (next: http://localhost:49356/tests/dromaeo/cssquery-dojo.html) 17:57:51 INFO - PID 1910 | RSS: Main: 275578880 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | __start_tp_report 17:57:51 INFO - PID 1910 | _x_x_mozilla_page_load 17:57:51 INFO - PID 1910 | _x_x_mozilla_page_load_details 17:57:51 INFO - PID 1910 | |i|pagename|runs| 17:57:51 INFO - PID 1910 | |0;dojo.html;3002.759779217663;3568.5717713874337;3572.05340584745;3593.8562457501703;3623.547056617923;5081.923771143432;6117.766507567577;6194.752209911604;6255.499560035198;6262.307207900601;6166.876662466748;6178.474829639475;6193.194884664998;6244.750209991595;6270.18487596613;5396.622236443451;5430.239766432693;5435.157550579666;5464.9180262296095;5506.752196151183;5489.862753431156;5575.69333686647;5591.664500129985;5597.832065038056;5599.7480113394795;5323.866903327409;5403.243545903577;5479.068558345091;5488.313960754906;5544.86137846553;5481.095619222823;5521.475459831309;5526.834194974157;5535.142052981794;5553.861153471195;4348.28253338202;5475.5072043515875;5481.945180548183;5483.972580137113;5490.203920431515;6126.23422072241;6161.137440758297;6236.189295391616;6240.344763799826;6250.843728906768;6119.593952096689;6177.413145751147;6484.092227088211;6537.64042977636;6678.833029174261;6803.523753337268;6813.693383797741;6840.657967101625;6849.349311815369;6849.589024658537;6289.465395441393;6301.243850737941;6316.463100636452;6332.176817013758;6334.429901308904;6716.697748601325;6815.386615204656;6874.553154045071;6938.965305173543;6994.265602111806;6548.1814773153355;6609.5373323867825;6643.302453242437;6648.301928297556;6691.665416729242;6532.379423954717;6837.965810170917;6849.1438570178725;6858.3141685831015;6889.345512176335;6526.673666316665;6603.570767900071;6663.766768163091;6681.298463661343;6729.4279986200745;6712.42944349726;6723.66381680914;6725.2602213756445;6734.0572319875255;6761.0872532208505;5376.489233522809;5383.488568585978;5402.729863506809;5406.6215364924865;5427.48438898304;3807.2956503047026;4742.359781429463;4755.453122890843;4796.952030479651;4873.269009648581;6359.713812878432;6369.490440764727;6391.680415979183;6402.039694045838;6406.231252249667;6747;6791.388775010273;6811.727530898708;6824.351686589766;6879.862402752017;6270.2475702915945;6324.936750632435;6342.04869269604;6385.265694445198;6393.041043843461;4993.826450784063;5009.398872135367;5327.121025030904;5869.647821130746;6018.307894592091;6960.095187625576;6965.756198533027;6991.580505169706;7004.089468369079;7010.088688470568;5900;6551.541392102507;6766.255711871789;6813.761518346834;6862.4166945808165;5542.722863856871;6885.4835887309655;6965.059716938351;6986.196587392515;6999.650017499002;6735.4274886635185;6750.763723269662;6757.628330441873;6762.594244345355;6776.728930842711;6603.80188594343;6820.761273355409;6846.760363387457;6859.142607174103;6880.552764070319;6403.487720982217;6477.0608261800835;6543.411093001652;6595;6657.500687448364;6604.207495100619;6616.768413105518;6620.569662971891;6645.235797883305;6654.767083152066;6298.370162983665;6314.116023756769;6381.361863813581;6449.935760599448;6522.576032558059;6455.580387274812;6555.508336874849;6579.572327798678;6601.702923368556;6625.734970601122;6382.840428989127;6405.7117429714845;6428.132202152649;6431.807045788634;6435 17:57:51 INFO - PID 1910 | |1;ext.html;773.3387953299929;798.5168972771473;827.561392461995;1127.14900250311;1200.081937317952;30505.237369065755;36406.90779276619;36810.26379472409;36847.44728829063;36953;36439.271214575696;37014.62985370145;37041.25917481656;37042.259154816886;37123.814380928125;7480.700771969128;7943.245391687809;8092.797680057986;8097.797555061112;8099.878501822477;7997.560134192617;8034.437589368747;8057.6374063167295;8094.51432914027;8098.51408915467;8034.437589368747;8037;8113.391495637821;8121.4721043132;8129.634166462523;8062.435629505908;8065.516069035877;8069.6368663410285;8105.027396712373;8109.189081091903;7915.920840791576;7926.484778489437;7945.205479452066;7956.801079972989;7973.521588704696;33299;33330.83334583336;33489;33568.83215583931;33592.99221023373;3556.5198698175673;3574.035010547164;3595.694365978895;3601.5318008658705;3607.8196090195647;27183.592246116325;27419.17742467729;27453;27538.449231015467;27611.585826212624;2992.7156920092557;3065.708757668018;3082.5067989121635;3089.4439000979814;3099.039297817661;230300;238552;240459;266468.33531664626;279801;43037.92405189895;43352.56647433485;43389;43402;43468;45260.54739452629;45310.281383117115;45597.77201113973;45746.542534574226;45756.542434575225;13558.525451609146;13588.864111358958;13661.590152295446;13671.043026988214;13681.179129252278;11316.094712422986;11332.6033588824;11334;11334.829977550344;11352.886471135349;12584.4337004835;12591.055670824724;12620.936895315464;12623.990080793514;12715;4809.639277054235;4865.6107511399005;5818.883622327529;5846.766129354778;5896.498797602166;771.4792515052317;981.74474636595;993.9552720127612;999.1357475783395;1006.9244806639531;22644.773552264596;22972.7702722974;23272.3018309451;23277.3016809496;23548.058077677044;1011.7521207304111;1038.6624347087227;1123.1071298317856;1123.8314252861971;1127.6560649002133;9075;11906;11916.404179790976;11954.52181912731;12008;273007.63496182393;273408;293456;296300;298409;41282;48649.27026094611;48856.51143488519;49118.75440622774;49137.26294105591;1377.738229736347;1422.6158937087137;1456.1627064437778;1497.4309762290477;1503.128185652319;19722.408327750192;19997.60004799925;22002.88998554997;28300.15099547017;29060.854695726386;44001.339979900964;44388;44466.88832779206;44501.554984449744;44604;251854.4814551831;252813;253294.7335263312;255020;255113;32398.676013239565;33989.98030059102;34177.31645367129;34266.657333426345;34414.311713765084;40210;41826.95432614209;41971.160576788905;42029;42113.15773684448;28235.15294541167;28373.858130709214;28702.856485718272;28938.42123157567;29816.403671926873;28370.716292837635;29436.411271774872;29786.404271914005;29938.8503057492;30265.848670756506;34322.79870204531;35166;35167.47248791219;35237.82381088078;35338.293234135686;21249;28866.97931144884;29841;29849.403011940074;30074.69925300719;17616.647667046844;23448.765512344657;23769;23849.165279215144;23909.641355379335 17:57:51 INFO - PID 1910 | |2;jquery.html;440.90961352922653;441.50110375275955;442.762478871554;454.22781271837874;466.3145176590413;19104.904475477622;20796.584068318625;21361.252356167537;21476.57046859062;21568.352949411525;21799;23066.30801075966;23338.883305583448;23502.529949401003;23728;5936.168936348915;6093;6132.938670613304;6161.199044124258;6176.073588961669;5877.941220587803;6076.4834989025985;6154.169187159721;6162.506999440034;6173.506119510451;5872.383399743008;6037.547183961198;6048.697565121726;6107.3587273336325;6128.938710612903;6038.57729958903;6070.484008859252;6095.542834287402;6102.084687296897;6120.785772497963;5369.731513424314;5385.865353366158;5428.809991650273;5436.103042997901;5437;23207.767922320898;24192.758072419227;25667;26052.60921086185;26116.8694156528;1745.694503461889;2187.8687278763327;2203.46015226269;2224.3104637562406;2225.432514708739;17694.203760830795;17910.925344479372;17972.640547188985;18178.63642727138;18186;1916.3867562380044;2117.2272120676;2158.0720290275176;2179.259051922338;2186.180182431588;113010.43494782473;143005;143517;143858.99999999895;145335.0932981345;12557.121001529806;12894;12991.415386307639;13017.609471715863;13073.869261307455;16491.83508164903;16722.58193545171;16746.74879876803;16815.831841681673;16835.74746378805;8730.388872778845;8989.55052247398;9051.81896362069;9260.259179265642;9273.258139348836;8623.094575069654;8637.17946795066;8642.697505587274;8800.559972001502;8829.64681412749;7051.8237044073285;7824.982752242286;8009.359251259885;8066.838663226703;8144.981877265342;2233.2630232023394;2242.1143648258844;2247.6403775396184;2257.537204872997;2259.2770313499523;967.9661211857551;969.3650658818485;995.9950200248952;996.9003099689972;1037.419045334615;15510.767338489932;16170.27233774483;19470.902645486964;21427.250046248617;21547.030383632773;817.2113910076686;1000.8598796168542;1005.8884932150015;1028.5063169678597;1049.1763965287214;7760.689572417153;9637.951810240904;9945.950270248602;11045.28205666629;11113.166512511594;499569;509397.62409503205;517376;521791;524258;88292;117539;118111;120880;123276;1041;1189.108168873345;1210.3585099897068;1218.7196944702769;1225.40567824605;18349.81650183481;18429.723554146967;18572.535686607942;18583.51331893472;18839.058047097864;53153.13964011241;53976.19035714546;54049.18926216031;55214.895702086535;55623;73133;73478;73922.52154956764;74896;75600.62199689186;8689.30485561141;8867.320062174125;8993.820123597361;9103.408278461879;9269.953650231706;12150.878491215328;13697.178169309873;14059.22674252926;14121.2233327168;14259.572212833216;10673.786524269315;10865.076468500267;10938.070264027647;10970.396628185526;11170;10517.10604598587;10744.892551074701;10865.945670271914;11031;11129.777404451703;10717.839232411676;11008.064314533354;11094.44527773592;11252;11479.770404592029;8942.46345219289;8997.955010224907;9008.954955225181;9023.864642030507;9054.230390416626;11135.164862635496;11138.050841102937;11185.608503702331;11206.271592346471;11277.71805704864 17:57:51 INFO - PID 1910 | |3;mootools.html;1381.5233744358197;1437.5974727076416;1441.437839242695;1449.5941136481786;1458.5624312706186;11213;11395.202335836495;11773.529058837647;11847.822282665757;12140.210886292407;11174.720631984184;11385.487653055632;11437.428128593558;11452.656420307383;11469;2188.245055455865;2468.827182097254;2566;2579.1617724239604;2584;2497.425592113815;2532.987335063322;2549.7195308516048;2556.143691863228;2562.948741025178;2503.2114883811632;2523.684539432571;2556.6548515950294;2559.2322303309024;2559.4753075619456;2139.7539282982343;2485.9751402486013;2498.1880888711153;2502.9612710725028;2533.9239822805343;2450.387403149213;2495.051880285498;2510.447701505684;2543.0844895837486;2554.067765265683;11260.268082574607;11335.263207891461;11449.198556101077;11449.713757156054;11464.25482343645;6395.424411802914;6558.049082883003;6579.868402631921;6672.099266598995;6679.76620818269;3588.6949609283242;3889.630485103939;4169.395437661552;4198.265303571863;4235.195312890572;1139.0033720494566;1187.5665382135544;1205;1213.5327898759042;1218.1107791312304;15821.288042038135;16112.19439028044;16199.757003644954;16228.10745409002;16278.941868778491;4522.66080043998;5317.229001794718;5399.5950303726595;5529.060059789846;5543.251661025791;11312.434378281218;11545.018673412855;11550.768984620261;11604;11611.419429028514;6378.712957916905;6426;6446.709898054599;6452.4515416189215;6458.806235812933;5862.824115276549;6016.939830601638;6183.474404675653;6192.535559833031;6201.875962480725;4146.688998325078;4260.062786187096;4280.636145927631;4282.379055037002;4302.290122129876;1239.1016513028162;1253.8595518961336;1260.1997731440601;1262.9896083133456;1266.7593157300269;794.1462927353118;795.6697970342243;813.979650508742;818.6029775558849;826.9462484938459;6079.75680972765;6082.178905847654;6189.8452538686;6281.8743625127245;6362;476.83042158180143;495.52429667519584;514.5420575687641;561.9297587801525;607.0802733858209;3885.7668539887695;4094.488188976378;4295.22685916538;4310.50429200646;4395.802188901507;14451.855481445262;14475.855241447662;14765.187914664797;14774.556763297118;14790.260486975609;14253.714925701428;14510.201938893251;14670;14678.85321146775;14767.778483322758;8028.518288902801;8279.461834980708;8464.576771161417;8468.618912148844;8473.5339556325;6874.484413668894;7135.286471352823;7186.640667966685;7193.748218812317;7261.455390845602;9327.860082098638;12938.417771200131;13194;13468.461261549428;13622.182669039494;12171.208871423329;13523.323833808467;13547.525836595672;13606.319684015958;13983.930080349533;8134.349252059703;8318.292945099734;8393.622286996977;8421.368397370099;8429.114942930904;10266.897331026892;10325.63860264887;10374.377537347482;10400.219983501116;10416.062554370143;8747.256483198998;8779.385443018928;8806.471611703319;8849.336299777413;8876.423032502867;7148.213696493278;7362.7423040193335;8508.574571271536;8569.442986205877;8647.524386158822;6594.406503414716;7475.2151024141685;7779.922200778146;7971.561564114029;8063.032436107705;5936;5997.460228579449;6039.939600603938;6040.6979651018155;6201.875962480635;5279.762410691605;6042.235826669577;6118.143459915526;6165.599236049642;6233.75064997395 17:57:51 INFO - PID 1910 | |4;prototype.html;342.62482581573175;382.0315500206976;386.72735721316474;413.8572192593554;414.33912908910276;4534.977325113374;6011;6204.293819199723;6383.872322553546;6383.936160638392;5305.885763989557;5606.579506537016;6046.12331211974;6176.313303712888;6195.504359651229;4513.796879140446;4519.6441067679725;4534.9546504534865;4550.180967425862;4559.452865656126;3627.5828279747902;4100.5489396166395;4506.684532082739;4518.570735780112;4531.456225252974;4033.9394909076386;4360.803763830635;4438.868088637393;4450.599446049855;4482.417285752864;3856.305864944323;4482.61897738691;4487.339684316791;4492.326151077332;4501.549845015505;3721;3816.969418257083;3826.885193444201;3842.426543314716;3846.2307538492414;5167.354080739908;6013.248343957005;6032.698365081728;6033.944059789563;6036.96981515094;5317.335333083364;5338.359396872362;5344.839654810362;5353.357597088336;5371.328583927009;5246.554042906319;5733.799317023884;5989.850253743648;6570.868582628368;6672.866542669168;3421.623621401644;4371.213181627306;4385.386045953569;4400.7579583122915;4474.194644963905;6148.84627884302;6524.478041756695;6683.53215274931;7032.648367581601;7158.391536719385;3647.7446578739764;4246.893827654271;4389.495208051051;4389.736615803062;4390.143921935192;3700.9829642844716;4490.326451032372;4493.303537951622;4506;4515.593596576324;4388.27593447084;5168.948310516922;5318.414974352895;5328.8401347959625;5342.866428339323;5468.398476167618;6629.3370662934285;6673.099131617267;6720.865582688319;6722.66386680664;1378.4348417148922;1513.954581362561;1529.8776097912141;1546.1418912503668;1548.5276990518;857.5069335132199;942.6229508196776;963.5856581669809;998.9450580218157;1010.4442556593845;5263.815766448156;5535.363433205233;5591.804286849941;6280;6345.36546345371;5916.349201587908;6789.8796698545175;6927.168739751161;6932.376086152271;6960.825979350557;5277.841664749987;6310.337414571404;6324.861524925557;6420.357964203542;6427.453666438311;3338.9332213356083;3583.8387272572795;3606.062423769839;3633.927321453556;3649.2701459708164;6725.8654826903185;7051.576905385693;7091.787246382617;7136.464765142531;7137.536060156073;5649.293838270216;6449.516286278548;7076.893846592306;7119.608421536762;7135.429165666734;6904.344087311697;7085.397741192057;7225.385842203366;7225.9522369256165;7239.8552028960175;6895.827604309932;7031.332023457763;7039.788806335818;7045.260247674126;7047.224805271314;5557.666160121623;5974.342822289632;6608.2575731071065;6854.588724676535;6880.965595171992;6430.678466076584;7218.89171662436;7225.747098851515;7249.782506524813;7304.488685791944;3669.8348574314755;3716.5168528091176;4192.323070771716;4216.746995180299;4411.051623900877;5720.971395142998;6776.96611516959;6948.791536253921;7004.754833580801;7069.293070692889;6712.765053223114;6730.562513436611;6775.390214880685;6788.321167883172;6794.728210871707;5253.894922101613;5507.421720719267;6203.8596684143895;6705.765298214539;6795.694193761194;5757.553039958085;6053.636781793107;6202.751889924533;6422.3577642235405;6442.452391546771;5359.383670877743;6549.705263263069;6704.513652900562;6758.080313418107;6780.762673306411;5872.913511000478;6373.5857169283845;6517.047044246502;6561.737530498726;6583.275839657538 17:57:51 INFO - PID 1910 | |5;yui.html;666.3902529185796;812.8130529978101;838.7064527415405;847.245951103518;848.2154007543021;16413.34346626135;16864.49406517805;16879.49361519153;18579.81420185798;19139.33012344568;17875.821241787577;17978.730319045193;18391.172397242124;18622.72065919009;18793.90603046986;971.5142428785607;992.1566668331897;995.5520015992814;997.7655251016006;1002.7443002034471;1000.5447521377756;1002.3134153105146;1003.5985605757683;1004.4726518577733;1006.0593345222204;951.8524628682566;1033.9844902326472;1035.969210635419;1037.8339465685492;1039.5270152080823;1021.5147804792738;1033.188946676855;1042.2235434601232;1045.2630895218929;1046.5133712823535;747.7794050754986;780.5862892666839;781.8240895798457;788.4874831359605;791.9722809701633;16831.495055148363;16835.74746378805;16935.237914293764;17038.48884533466;17058.14709264532;17217.05306208158;17240.913795431068;17471.340222678824;17592.12039398025;17613.7357939631;14227.644308892257;14377.209253491053;14419.567412977627;14599.562013139623;14617.634559136;3159.6208454985317;3490.1100219444133;3572.5534308211472;3579.6420357964257;3582.731295152848;108061.45969270103;125527;127356;129038;129676;13468.5286014989;13563.25402102874;13663.863361366459;13729.244891531062;13778;19779.703304450446;21822.345329640135;22681.659775103388;25335.493290134094;25718.87140564285;7874.960625196837;7976.441649084624;7993.120756716757;8048.839023219502;8051;3855.8264878080554;3896.2402331545645;3934.8229329680235;3938.6455219030427;3941.7832019239218;3556.3776339140545;4208.389783481378;4274.123804620045;4350.934735978963;4352.7823608819435;930.5300823084299;941.4257303045136;942.9858552121724;948.9003654616304;951.6764300137847;564.858785303674;634.5970308853819;649.7076315657878;654.2574178307655;667.5027104806853;8866.113388661082;10222.846657300146;10482.213833962483;10817.621383251706;12253.081018923616;637.5409705012477;639.4117411980989;652.738904438219;665.3845193196274;680.9523333366617;3901.7463864848696;4275.657947364203;5572.554195664337;5665.235193248941;5752.482276595127;70419;88612.5569372149;97047.51476242715;97861.02138978662;98839.50580247052;69389;80833;82499;86015.4089984149;87998;74213.628931855;74320.19637937938;75405;75500;76463;11249.550017999189;11338;11480.712982175512;11582.478788454708;11739.002184814386;30057.248568785955;37432.438513421774;41625;41780.58219417888;45311.77344113258;55353.44646553483;70063;74192;74495;77606.28378006743;14075.296235188405;17279.22243499071;17309.221085051453;17592.120393980505;17699.203535841174;18129.546761330545;18701.90649046746;18810.153543090866;18829.90585047066;19076.809231908057;17024.74462883033;17620.73568896493;17701;17769.111544422467;17905.19426625779;14274.429022838971;16694.24875880564;17506.86205396645;17955.551111222325;18239.270429183216;16632.833671663127;16715.414960476326;16834.57913552171;17023.659526809643;17148;9959;11020.118390528578;11275.830862537232;11308.095352371955;11448.026917711755;13849.723005539632;14029.368678409292;14066.085704429179;14104.435822566982;14128.646783830487 17:57:51 INFO - PID 1910 | __end_tp_report 17:57:51 INFO - PID 1910 | __start_cc_report 17:57:51 INFO - PID 1910 | _x_x_mozilla_cycle_collect,344 17:57:51 INFO - PID 1910 | __end_cc_report 17:57:51 INFO - PID 1910 | __startTimestamp1495241871271__endTimestamp 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | ------- Summary: start ------- 17:57:51 INFO - PID 1910 | Number of tests: 6 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | [#0] dojo.html Cycles:165 Average:6170.78 Median:6403.49 stddev:781.33 (12.2%) stddev-sans-first:743.15 17:57:51 INFO - PID 1910 | Values: 3002.8 3568.6 3572.1 3593.9 3623.5 5081.9 6117.8 6194.8 6255.5 6262.3 6166.9 6178.5 6193.2 6244.8 6270.2 5396.6 5430.2 5435.2 5464.9 5506.8 5489.9 5575.7 5591.7 5597.8 5599.7 5323.9 5403.2 5479.1 5488.3 5544.9 5481.1 5521.5 5526.8 5535.1 5553.9 4348.3 5475.5 5481.9 5484.0 5490.2 6126.2 6161.1 6236.2 6240.3 6250.8 6119.6 6177.4 6484.1 6537.6 6678.8 6803.5 6813.7 6840.7 6849.3 6849.6 6289.5 6301.2 6316.5 6332.2 6334.4 6716.7 6815.4 6874.6 6939.0 6994.3 6548.2 6609.5 6643.3 6648.3 6691.7 6532.4 6838.0 6849.1 6858.3 6889.3 6526.7 6603.6 6663.8 6681.3 6729.4 6712.4 6723.7 6725.3 6734.1 6761.1 5376.5 5383.5 5402.7 5406.6 5427.5 3807.3 4742.4 4755.5 4797.0 4873.3 6359.7 6369.5 6391.7 6402.0 6406.2 6747.0 6791.4 6811.7 6824.4 6879.9 6270.2 6324.9 6342.0 6385.3 6393.0 4993.8 5009.4 5327.1 5869.6 6018.3 6960.1 6965.8 6991.6 7004.1 7010.1 5900.0 6551.5 6766.3 6813.8 6862.4 5542.7 6885.5 6965.1 6986.2 6999.7 6735.4 6750.8 6757.6 6762.6 6776.7 6603.8 6820.8 6846.8 6859.1 6880.6 6403.5 6477.1 6543.4 6595.0 6657.5 6604.2 6616.8 6620.6 6645.2 6654.8 6298.4 6314.1 6381.4 6449.9 6522.6 6455.6 6555.5 6579.6 6601.7 6625.7 6382.8 6405.7 6428.1 6431.8 6435.0 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | [#1] ext.html Cycles:180 Average:41044.86 Median:33409.92 stddev:69201.65 (207.1%) stddev-sans-first:69329.72 17:57:51 INFO - PID 1910 | Values: 773.3 798.5 827.6 1127.1 1200.1 30505.2 36406.9 36810.3 36847.4 36953.0 36439.3 37014.6 37041.3 37042.3 37123.8 7480.7 7943.2 8092.8 8097.8 8099.9 7997.6 8034.4 8057.6 8094.5 8098.5 8034.4 8037.0 8113.4 8121.5 8129.6 8062.4 8065.5 8069.6 8105.0 8109.2 7915.9 7926.5 7945.2 7956.8 7973.5 33299.0 33330.8 33489.0 33568.8 33593.0 3556.5 3574.0 3595.7 3601.5 3607.8 27183.6 27419.2 27453.0 27538.4 27611.6 2992.7 3065.7 3082.5 3089.4 3099.0 230300.0 238552.0 240459.0 266468.3 279801.0 43037.9 43352.6 43389.0 43402.0 43468.0 45260.5 45310.3 45597.8 45746.5 45756.5 13558.5 13588.9 13661.6 13671.0 13681.2 11316.1 11332.6 11334.0 11334.8 11352.9 12584.4 12591.1 12620.9 12624.0 12715.0 4809.6 4865.6 5818.9 5846.8 5896.5 771.5 981.7 994.0 999.1 1006.9 22644.8 22972.8 23272.3 23277.3 23548.1 1011.8 1038.7 1123.1 1123.8 1127.7 9075.0 11906.0 11916.4 11954.5 12008.0 273007.6 273408.0 293456.0 296300.0 298409.0 41282.0 48649.3 48856.5 49118.8 49137.3 1377.7 1422.6 1456.2 1497.4 1503.1 19722.4 19997.6 22002.9 28300.2 29060.9 44001.3 44388.0 44466.9 44501.6 44604.0 251854.5 252813.0 253294.7 255020.0 255113.0 32398.7 33990.0 34177.3 34266.7 34414.3 40210.0 41827.0 41971.2 42029.0 42113.2 28235.2 28373.9 28702.9 28938.4 29816.4 28370.7 29436.4 29786.4 29938.9 30265.8 34322.8 35166.0 35167.5 35237.8 35338.3 21249.0 28867.0 29841.0 29849.4 30074.7 17616.6 23448.8 23769.0 23849.2 23909.6 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | [#2] jquery.html Cycles:180 Average:33318.44 Median:2258.41 stddev:86827.35 (3844.6%) stddev-sans-first:87035.83 17:57:51 INFO - PID 1910 | Values: 440.9 441.5 442.8 454.2 466.3 19104.9 20796.6 21361.3 21476.6 21568.4 21799.0 23066.3 23338.9 23502.5 23728.0 5936.2 6093.0 6132.9 6161.2 6176.1 5877.9 6076.5 6154.2 6162.5 6173.5 5872.4 6037.5 6048.7 6107.4 6128.9 6038.6 6070.5 6095.5 6102.1 6120.8 5369.7 5385.9 5428.8 5436.1 5437.0 23207.8 24192.8 25667.0 26052.6 26116.9 1745.7 2187.9 2203.5 2224.3 2225.4 17694.2 17910.9 17972.6 18178.6 18186.0 1916.4 2117.2 2158.1 2179.3 2186.2 113010.4 143005.0 143517.0 143859.0 145335.1 12557.1 12894.0 12991.4 13017.6 13073.9 16491.8 16722.6 16746.7 16815.8 16835.7 8730.4 8989.6 9051.8 9260.3 9273.3 8623.1 8637.2 8642.7 8800.6 8829.6 7051.8 7825.0 8009.4 8066.8 8145.0 2233.3 2242.1 2247.6 2257.5 2259.3 968.0 969.4 996.0 996.9 1037.4 15510.8 16170.3 19470.9 21427.3 21547.0 817.2 1000.9 1005.9 1028.5 1049.2 7760.7 9638.0 9946.0 11045.3 11113.2 499569.0 509397.6 517376.0 521791.0 524258.0 88292.0 117539.0 118111.0 120880.0 123276.0 1041.0 1189.1 1210.4 1218.7 1225.4 18349.8 18429.7 18572.5 18583.5 18839.1 53153.1 53976.2 54049.2 55214.9 55623.0 73133.0 73478.0 73922.5 74896.0 75600.6 8689.3 8867.3 8993.8 9103.4 9270.0 12150.9 13697.2 14059.2 14121.2 14259.6 10673.8 10865.1 10938.1 10970.4 11170.0 10517.1 10744.9 10865.9 11031.0 11129.8 10717.8 11008.1 11094.4 11252.0 11479.8 8942.5 8998.0 9009.0 9023.9 9054.2 11135.2 11138.1 11185.6 11206.3 11277.7 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | [#3] mootools.html Cycles:180 Average:6904.91 Median:3887.70 stddev:4374.46 (112.5%) stddev-sans-first:4367.04 17:57:51 INFO - PID 1910 | Values: 1381.5 1437.6 1441.4 1449.6 1458.6 11213.0 11395.2 11773.5 11847.8 12140.2 11174.7 11385.5 11437.4 11452.7 11469.0 2188.2 2468.8 2566.0 2579.2 2584.0 2497.4 2533.0 2549.7 2556.1 2562.9 2503.2 2523.7 2556.7 2559.2 2559.5 2139.8 2486.0 2498.2 2503.0 2533.9 2450.4 2495.1 2510.4 2543.1 2554.1 11260.3 11335.3 11449.2 11449.7 11464.3 6395.4 6558.0 6579.9 6672.1 6679.8 3588.7 3889.6 4169.4 4198.3 4235.2 1139.0 1187.6 1205.0 1213.5 1218.1 15821.3 16112.2 16199.8 16228.1 16278.9 4522.7 5317.2 5399.6 5529.1 5543.3 11312.4 11545.0 11550.8 11604.0 11611.4 6378.7 6426.0 6446.7 6452.5 6458.8 5862.8 6016.9 6183.5 6192.5 6201.9 4146.7 4260.1 4280.6 4282.4 4302.3 1239.1 1253.9 1260.2 1263.0 1266.8 794.1 795.7 814.0 818.6 826.9 6079.8 6082.2 6189.8 6281.9 6362.0 476.8 495.5 514.5 561.9 607.1 3885.8 4094.5 4295.2 4310.5 4395.8 14451.9 14475.9 14765.2 14774.6 14790.3 14253.7 14510.2 14670.0 14678.9 14767.8 8028.5 8279.5 8464.6 8468.6 8473.5 6874.5 7135.3 7186.6 7193.7 7261.5 9327.9 12938.4 13194.0 13468.5 13622.2 12171.2 13523.3 13547.5 13606.3 13983.9 8134.3 8318.3 8393.6 8421.4 8429.1 10266.9 10325.6 10374.4 10400.2 10416.1 8747.3 8779.4 8806.5 8849.3 8876.4 7148.2 7362.7 8508.6 8569.4 8647.5 6594.4 7475.2 7779.9 7971.6 8063.0 5936.0 5997.5 6039.9 6040.7 6201.9 5279.8 6042.2 6118.1 6165.6 6233.8 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | [#4] prototype.html Cycles:180 Average:5287.27 Median:5945.35 stddev:1727.70 (29.1%) stddev-sans-first:1692.21 17:57:51 INFO - PID 1910 | Values: 342.6 382.0 386.7 413.9 414.3 4535.0 6011.0 6204.3 6383.9 6383.9 5305.9 5606.6 6046.1 6176.3 6195.5 4513.8 4519.6 4535.0 4550.2 4559.5 3627.6 4100.5 4506.7 4518.6 4531.5 4033.9 4360.8 4438.9 4450.6 4482.4 3856.3 4482.6 4487.3 4492.3 4501.5 3721.0 3817.0 3826.9 3842.4 3846.2 5167.4 6013.2 6032.7 6033.9 6037.0 5317.3 5338.4 5344.8 5353.4 5371.3 5246.6 5733.8 5989.9 6570.9 6672.9 3421.6 4371.2 4385.4 4400.8 4474.2 6148.8 6524.5 6683.5 7032.6 7158.4 3647.7 4246.9 4389.5 4389.7 4390.1 3701.0 4490.3 4493.3 4506.0 4515.6 4388.3 5168.9 5318.4 5328.8 5342.9 5468.4 6629.3 6673.1 6720.9 6722.7 1378.4 1514.0 1529.9 1546.1 1548.5 857.5 942.6 963.6 998.9 1010.4 5263.8 5535.4 5591.8 6280.0 6345.4 5916.3 6789.9 6927.2 6932.4 6960.8 5277.8 6310.3 6324.9 6420.4 6427.5 3338.9 3583.8 3606.1 3633.9 3649.3 6725.9 7051.6 7091.8 7136.5 7137.5 5649.3 6449.5 7076.9 7119.6 7135.4 6904.3 7085.4 7225.4 7226.0 7239.9 6895.8 7031.3 7039.8 7045.3 7047.2 5557.7 5974.3 6608.3 6854.6 6881.0 6430.7 7218.9 7225.7 7249.8 7304.5 3669.8 3716.5 4192.3 4216.7 4411.1 5721.0 6777.0 6948.8 7004.8 7069.3 6712.8 6730.6 6775.4 6788.3 6794.7 5253.9 5507.4 6203.9 6705.8 6795.7 5757.6 6053.6 6202.8 6422.4 6442.5 5359.4 6549.7 6704.5 6758.1 6780.8 5872.9 6373.6 6517.0 6561.7 6583.3 17:57:51 INFO - PID 1910 | 17:57:51 INFO - PID 1910 | [#5] yui.html Cycles:180 Average:21344.75 Median:18953.36 stddev:29244.20 (154.3%) stddev-sans-first:29285.01 17:57:51 INFO - PID 1910 | Values: 666.4 812.8 838.7 847.2 848.2 16413.3 16864.5 16879.5 18579.8 19139.3 17875.8 17978.7 18391.2 18622.7 18793.9 971.5 992.2 995.6 997.8 1002.7 1000.5 1002.3 1003.6 1004.5 1006.1 951.9 1034.0 1036.0 1037.8 1039.5 1021.5 1033.2 1042.2 1045.3 1046.5 747.8 780.6 781.8 788.5 792.0 16831.5 16835.7 16935.2 17038.5 17058.1 17217.1 17240.9 17471.3 17592.1 17613.7 14227.6 14377.2 14419.6 14599.6 14617.6 3159.6 3490.1 3572.6 3579.6 3582.7 108061.5 125527.0 127356.0 129038.0 129676.0 13468.5 13563.3 13663.9 13729.2 13778.0 19779.7 21822.3 22681.7 25335.5 25718.9 7875.0 7976.4 7993.1 8048.8 8051.0 3855.8 3896.2 3934.8 3938.6 3941.8 3556.4 4208.4 4274.1 4350.9 4352.8 930.5 941.4 943.0 948.9 951.7 564.9 634.6 649.7 654.3 667.5 8866.1 10222.8 10482.2 10817.6 12253.1 637.5 639.4 652.7 665.4 681.0 3901.7 4275.7 5572.6 5665.2 5752.5 70419.0 88612.6 97047.5 97861.0 98839.5 69389.0 80833.0 82499.0 86015.4 87998.0 74213.6 74320.2 75405.0 75500.0 76463.0 11249.6 11338.0 11480.7 11582.5 11739.0 30057.2 37432.4 41625.0 41780.6 45311.8 55353.4 70063.0 74192.0 74495.0 77606.3 14075.3 17279.2 17309.2 17592.1 17699.2 18129.5 18701.9 18810.2 18829.9 19076.8 17024.7 17620.7 17701.0 17769.1 17905.2 14274.4 16694.2 17506.9 17955.6 18239.3 16632.8 16715.4 16834.6 17023.7 17148.0 9959.0 11020.1 11275.8 11308.1 11448.0 13849.7 14029.4 14066.1 14104.4 14128.6 17:57:51 INFO - PID 1910 | -------- Summary: end -------- 17:57:51 INFO - PID 1910 | 17:57:51 INFO - TEST-INFO | 1910: exit 0 17:57:51 INFO - TEST-OK | dromaeo_css | took 1113417ms 17:57:51 INFO - TEST-START | kraken 17:57:51 INFO - operating with platform_type : mac_ 17:57:51 INFO - Initialising browser for kraken test... 17:57:51 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpKb92Bj/profile http://localhost:49356/getInfo.html) 17:57:52 INFO - PID 1919 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2963: String.localeCompare is deprecated; use String.prototype.localeCompare instead 17:57:54 INFO - PID 1919 | 2017-05-19 17:57:54.377 plugin-container[1920:17560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 17:57:54 INFO - PID 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:54 INFO - PID 1919 | 2017-05-19 17:57:54.381 plugin-container[1920:17560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0f, name = 'com.apple.CFPasteboardClient' 17:57:54 INFO - PID 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:54 INFO - PID 1919 | 2017-05-19 17:57:54.381 plugin-container[1920:17560] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:54 INFO - PID 1919 | __metrics Screen width/height:1600/1200 17:57:54 INFO - PID 1919 | colorDepth:24 17:57:54 INFO - PID 1919 | Browser inner width/height: 1024/691 17:57:54 INFO - PID 1919 | __metrics 17:57:55 INFO - TEST-INFO | 1919: exit 0 17:57:55 INFO - Browser initialized. 17:57:55 INFO - Running cycle 1/1 for kraken test... 17:57:55 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpKb92Bj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:57:57 INFO - PID 1921 | 2017-05-19 17:57:57.465 plugin-container[1922:17672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 17:57:57 INFO - PID 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:57 INFO - PID 1921 | 2017-05-19 17:57:57.466 plugin-container[1922:17672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 17:57:57 INFO - PID 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:57 INFO - PID 1921 | 2017-05-19 17:57:57.466 plugin-container[1922:17672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:58 INFO - PID 1921 | 2017-05-19 17:57:58.079 plugin-container[1923:17718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 17:57:58 INFO - PID 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:58 INFO - PID 1921 | 2017-05-19 17:57:58.079 plugin-container[1923:17718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9713, name = 'com.apple.CFPasteboardClient' 17:57:58 INFO - PID 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:58 INFO - PID 1921 | 2017-05-19 17:57:58.080 plugin-container[1923:17718] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:58:07 INFO - PID 1921 | RSS: Main: 255864832 17:58:07 INFO - PID 1921 | 17:58:10 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-beat-detection) 17:58:11 INFO - PID 1921 | RSS: Main: 263606272 17:58:11 INFO - PID 1921 | 17:58:13 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-dft) 17:58:14 INFO - PID 1921 | RSS: Main: 271470592 17:58:14 INFO - PID 1921 | 17:58:17 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-fft) 17:58:18 INFO - PID 1921 | RSS: Main: 267186176 17:58:18 INFO - PID 1921 | 17:58:20 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-oscillator) 17:58:21 INFO - PID 1921 | RSS: Main: 267943936 17:58:21 INFO - PID 1921 | 17:58:23 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49356/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:58:23 INFO - PID 1921 | RSS: Main: 268341248 17:58:23 INFO - PID 1921 | 17:58:26 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49356/tests/kraken/driver.html?testName=imaging-darkroom) 17:58:27 INFO - PID 1921 | RSS: Main: 279019520 17:58:27 INFO - PID 1921 | 17:58:30 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49356/tests/kraken/driver.html?testName=imaging-desaturate) 17:58:31 INFO - PID 1921 | RSS: Main: 279535616 17:58:31 INFO - PID 1921 | 17:58:33 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49356/tests/kraken/driver.html?testName=json-parse-financial) 17:58:34 INFO - PID 1921 | RSS: Main: 278528000 17:58:34 INFO - PID 1921 | 17:58:36 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49356/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:58:36 INFO - PID 1921 | RSS: Main: 278286336 17:58:36 INFO - PID 1921 | 17:58:38 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:58:39 INFO - PID 1921 | RSS: Main: 278364160 17:58:39 INFO - PID 1921 | 17:58:41 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:58:41 INFO - PID 1921 | RSS: Main: 277893120 17:58:41 INFO - PID 1921 | 17:58:44 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:58:45 INFO - PID 1921 | RSS: Main: 278220800 17:58:45 INFO - PID 1921 | 17:58:48 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:58:49 INFO - PID 1921 | RSS: Main: 278237184 17:58:49 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49356/tests/kraken/driver.html?testName=ai-astar) 17:58:51 INFO - PID 1921 | RSS: Main: 279953408 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | __start_tp_report 17:58:51 INFO - PID 1921 | _x_x_mozilla_page_load 17:58:51 INFO - PID 1921 | _x_x_mozilla_page_load_details 17:58:51 INFO - PID 1921 | |i|pagename|runs| 17:58:51 INFO - PID 1921 | |0;ai-astar;100;116;114;115;111;115;115;103;170;171 17:58:51 INFO - PID 1921 | |1;audio-beat-detection;151;114;97;170;145;151;142;98;157;145 17:58:51 INFO - PID 1921 | |2;audio-dft;205;203;199;243;250;213;196;208;272;263 17:58:51 INFO - PID 1921 | |3;audio-fft;81;71;82;73;53;51;54;92;92;86 17:58:51 INFO - PID 1921 | |4;audio-oscillator;85;70;88;84;72;71;84;69;70;100 17:58:51 INFO - PID 1921 | |5;imaging-gaussian-blur;134;89;88;89;125;83;126;137;96;89 17:58:51 INFO - PID 1921 | |6;imaging-darkroom;97;98;96;97;106;96;97;96;96;98 17:58:51 INFO - PID 1921 | |7;imaging-desaturate;94;90;92;92;92;92;91;93;93;92 17:58:51 INFO - PID 1921 | |8;json-parse-financial;55;70;71;63;50;53;51;55;58;65 17:58:51 INFO - PID 1921 | |9;json-stringify-tinderbox;54;55;62;56;59;60;59;58;55;49 17:58:51 INFO - PID 1921 | |10;stanford-crypto-aes;86;86;85;85;85;87;94;86;73;81 17:58:51 INFO - PID 1921 | |11;stanford-crypto-ccm;150;154;154;151;162;160;165;154;191;140 17:58:51 INFO - PID 1921 | |12;stanford-crypto-pbkdf2;204;220;188;189;191;188;190;230;190;182 17:58:51 INFO - PID 1921 | |13;stanford-crypto-sha256-iterative;73;98;103;79;63;96;100;97;103;67 17:58:51 INFO - PID 1921 | __end_tp_report 17:58:51 INFO - PID 1921 | __start_cc_report 17:58:51 INFO - PID 1921 | _x_x_mozilla_cycle_collect,928 17:58:51 INFO - PID 1921 | __end_cc_report 17:58:51 INFO - PID 1921 | __startTimestamp1495241931652__endTimestamp 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | ------- Summary: start ------- 17:58:51 INFO - PID 1921 | Number of tests: 14 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#0] ai-astar Cycles:10 Average:123.00 Median:115.00 stddev:25.62 (22.3%) stddev-sans-first:25.79 17:58:51 INFO - PID 1921 | Values: 100.0 116.0 114.0 115.0 111.0 115.0 115.0 103.0 170.0 171.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:154.00 stddev:25.13 (16.3%) stddev-sans-first:26.14 17:58:51 INFO - PID 1921 | Values: 151.0 114.0 97.0 170.0 145.0 151.0 142.0 98.0 157.0 145.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#2] audio-dft Cycles:10 Average:225.20 Median:228.00 stddev:28.74 (12.6%) stddev-sans-first:29.54 17:58:51 INFO - PID 1921 | Values: 205.0 203.0 199.0 243.0 250.0 213.0 196.0 208.0 272.0 263.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#3] audio-fft Cycles:10 Average:73.50 Median:81.50 stddev:15.93 (19.5%) stddev-sans-first:16.66 17:58:51 INFO - PID 1921 | Values: 81.0 71.0 82.0 73.0 53.0 51.0 54.0 92.0 92.0 86.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:78.00 stddev:10.45 (13.4%) stddev-sans-first:10.87 17:58:51 INFO - PID 1921 | Values: 85.0 70.0 88.0 84.0 72.0 71.0 84.0 69.0 70.0 100.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:105.60 Median:88.50 stddev:21.92 (24.8%) stddev-sans-first:20.70 17:58:51 INFO - PID 1921 | Values: 134.0 89.0 88.0 89.0 125.0 83.0 126.0 137.0 96.0 89.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:97.00 stddev:3.02 (3.1%) stddev-sans-first:3.19 17:58:51 INFO - PID 1921 | Values: 97.0 98.0 96.0 97.0 106.0 96.0 97.0 96.0 96.0 98.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.00 stddev:1.10 (1.2%) stddev-sans-first:0.93 17:58:51 INFO - PID 1921 | Values: 94.0 90.0 92.0 92.0 92.0 92.0 91.0 93.0 93.0 92.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:60.50 stddev:7.68 (12.7%) stddev-sans-first:8.00 17:58:51 INFO - PID 1921 | Values: 55.0 70.0 71.0 63.0 50.0 53.0 51.0 55.0 58.0 65.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:58.50 stddev:3.71 (6.3%) stddev-sans-first:3.81 17:58:51 INFO - PID 1921 | Values: 54.0 55.0 62.0 56.0 59.0 60.0 59.0 58.0 55.0 49.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:86.00 stddev:5.25 (6.1%) stddev-sans-first:5.55 17:58:51 INFO - PID 1921 | Values: 86.0 86.0 85.0 85.0 85.0 87.0 94.0 86.0 73.0 81.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:157.00 stddev:13.51 (8.6%) stddev-sans-first:14.01 17:58:51 INFO - PID 1921 | Values: 150.0 154.0 154.0 151.0 162.0 160.0 165.0 154.0 191.0 140.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.20 Median:190.50 stddev:15.82 (8.3%) stddev-sans-first:16.58 17:58:51 INFO - PID 1921 | Values: 204.0 220.0 188.0 189.0 191.0 188.0 190.0 230.0 190.0 182.0 17:58:51 INFO - PID 1921 | 17:58:51 INFO - PID 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:87.90 Median:76.00 stddev:15.67 (20.6%) stddev-sans-first:15.67 17:58:51 INFO - PID 1921 | Values: 73.0 98.0 103.0 79.0 63.0 96.0 100.0 97.0 103.0 67.0 17:58:51 INFO - PID 1921 | -------- Summary: end -------- 17:58:51 INFO - PID 1921 | 17:58:52 INFO - TEST-INFO | 1921: exit 0 17:58:52 INFO - TEST-OK | kraken | took 60702ms 17:58:52 INFO - SUITE-END | took 1174s 17:58:52 INFO - Completed test suite (00:19:34) 17:58:52 INFO - javascript benchmark 17:58:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7724.853706306947, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3002.759779217663, 3568.5717713874337, 3572.05340584745, 3593.8562457501703, 3623.547056617923, 5081.923771143432, 6117.766507567577, 6194.752209911604, 6255.499560035198, 6262.307207900601, 6166.876662466748, 6178.474829639475, 6193.194884664998, 6244.750209991595, 6270.18487596613, 5396.622236443451, 5430.239766432693, 5435.157550579666, 5464.9180262296095, 5506.752196151183, 5489.862753431156, 5575.69333686647, 5591.664500129985, 5597.832065038056, 5599.7480113394795, 5323.866903327409, 5403.243545903577, 5479.068558345091, 5488.313960754906, 5544.86137846553, 5481.095619222823, 5521.475459831309, 5526.834194974157, 5535.142052981794, 5553.861153471195, 4348.28253338202, 5475.5072043515875, 5481.945180548183, 5483.972580137113, 5490.203920431515, 6126.23422072241, 6161.137440758297, 6236.189295391616, 6240.344763799826, 6250.843728906768, 6119.593952096689, 6177.413145751147, 6484.092227088211, 6537.64042977636, 6678.833029174261, 6803.523753337268, 6813.693383797741, 6840.657967101625, 6849.349311815369, 6849.589024658537, 6289.465395441393, 6301.243850737941, 6316.463100636452, 6332.176817013758, 6334.429901308904, 6716.697748601325, 6815.386615204656, 6874.553154045071, 6938.965305173543, 6994.265602111806, 6548.1814773153355, 6609.5373323867825, 6643.302453242437, 6648.301928297556, 6691.665416729242, 6532.379423954717, 6837.965810170917, 6849.1438570178725, 6858.3141685831015, 6889.345512176335, 6526.673666316665, 6603.570767900071, 6663.766768163091, 6681.298463661343, 6729.4279986200745, 6712.42944349726, 6723.66381680914, 6725.2602213756445, 6734.0572319875255, 6761.0872532208505, 5376.489233522809, 5383.488568585978, 5402.729863506809, 5406.6215364924865, 5427.48438898304, 3807.2956503047026, 4742.359781429463, 4755.453122890843, 4796.952030479651, 4873.269009648581, 6359.713812878432, 6369.490440764727, 6391.680415979183, 6402.039694045838, 6406.231252249667, 6747.0, 6791.388775010273, 6811.727530898708, 6824.351686589766, 6879.862402752017, 6270.2475702915945, 6324.936750632435, 6342.04869269604, 6385.265694445198, 6393.041043843461, 4993.826450784063, 5009.398872135367, 5327.121025030904, 5869.647821130746, 6018.307894592091, 6960.095187625576, 6965.756198533027, 6991.580505169706, 7004.089468369079, 7010.088688470568, 5900.0, 6551.541392102507, 6766.255711871789, 6813.761518346834, 6862.4166945808165, 5542.722863856871, 6885.4835887309655, 6965.059716938351, 6986.196587392515, 6999.650017499002, 6735.4274886635185, 6750.763723269662, 6757.628330441873, 6762.594244345355, 6776.728930842711, 6603.80188594343, 6820.761273355409, 6846.760363387457, 6859.142607174103, 6880.552764070319, 6403.487720982217, 6477.0608261800835, 6543.411093001652, 6595.0, 6657.500687448364, 6604.207495100619, 6616.768413105518, 6620.569662971891, 6645.235797883305, 6654.767083152066, 6298.370162983665, 6314.116023756769, 6381.361863813581, 6449.935760599448, 6522.576032558059, 6455.580387274812, 6555.508336874849, 6579.572327798678, 6601.702923368556, 6625.734970601122, 6382.840428989127, 6405.7117429714845, 6428.132202152649, 6431.807045788634, 6435.0], "value": 6116.007555483515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.3387953299929, 798.5168972771473, 827.561392461995, 1127.14900250311, 1200.081937317952, 30505.237369065755, 36406.90779276619, 36810.26379472409, 36847.44728829063, 36953.0, 36439.271214575696, 37014.62985370145, 37041.25917481656, 37042.259154816886, 37123.814380928125, 7480.700771969128, 7943.245391687809, 8092.797680057986, 8097.797555061112, 8099.878501822477, 7997.560134192617, 8034.437589368747, 8057.6374063167295, 8094.51432914027, 8098.51408915467, 8034.437589368747, 8037.0, 8113.391495637821, 8121.4721043132, 8129.634166462523, 8062.435629505908, 8065.516069035877, 8069.6368663410285, 8105.027396712373, 8109.189081091903, 7915.920840791576, 7926.484778489437, 7945.205479452066, 7956.801079972989, 7973.521588704696, 33299.0, 33330.83334583336, 33489.0, 33568.83215583931, 33592.99221023373, 3556.5198698175673, 3574.035010547164, 3595.694365978895, 3601.5318008658705, 3607.8196090195647, 27183.592246116325, 27419.17742467729, 27453.0, 27538.449231015467, 27611.585826212624, 2992.7156920092557, 3065.708757668018, 3082.5067989121635, 3089.4439000979814, 3099.039297817661, 230300.0, 238552.0, 240459.0, 266468.33531664626, 279801.0, 43037.92405189895, 43352.56647433485, 43389.0, 43402.0, 43468.0, 45260.54739452629, 45310.281383117115, 45597.77201113973, 45746.542534574226, 45756.542434575225, 13558.525451609146, 13588.864111358958, 13661.590152295446, 13671.043026988214, 13681.179129252278, 11316.094712422986, 11332.6033588824, 11334.0, 11334.829977550344, 11352.886471135349, 12584.4337004835, 12591.055670824724, 12620.936895315464, 12623.990080793514, 12715.0, 4809.639277054235, 4865.6107511399005, 5818.883622327529, 5846.766129354778, 5896.498797602166, 771.4792515052317, 981.74474636595, 993.9552720127612, 999.1357475783395, 1006.9244806639531, 22644.773552264596, 22972.7702722974, 23272.3018309451, 23277.3016809496, 23548.058077677044, 1011.7521207304111, 1038.6624347087227, 1123.1071298317856, 1123.8314252861971, 1127.6560649002133, 9075.0, 11906.0, 11916.404179790976, 11954.52181912731, 12008.0, 273007.63496182393, 273408.0, 293456.0, 296300.0, 298409.0, 41282.0, 48649.27026094611, 48856.51143488519, 49118.75440622774, 49137.26294105591, 1377.738229736347, 1422.6158937087137, 1456.1627064437778, 1497.4309762290477, 1503.128185652319, 19722.408327750192, 19997.60004799925, 22002.88998554997, 28300.15099547017, 29060.854695726386, 44001.339979900964, 44388.0, 44466.88832779206, 44501.554984449744, 44604.0, 251854.4814551831, 252813.0, 253294.7335263312, 255020.0, 255113.0, 32398.676013239565, 33989.98030059102, 34177.31645367129, 34266.657333426345, 34414.311713765084, 40210.0, 41826.95432614209, 41971.160576788905, 42029.0, 42113.15773684448, 28235.15294541167, 28373.858130709214, 28702.856485718272, 28938.42123157567, 29816.403671926873, 28370.716292837635, 29436.411271774872, 29786.404271914005, 29938.8503057492, 30265.848670756506, 34322.79870204531, 35166.0, 35167.47248791219, 35237.82381088078, 35338.293234135686, 21249.0, 28866.97931144884, 29841.0, 29849.403011940074, 30074.69925300719, 17616.647667046844, 23448.765512344657, 23769.0, 23849.165279215144, 23909.641355379335], "value": 16598.79798425334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.90961352922653, 441.50110375275955, 442.762478871554, 454.22781271837874, 466.3145176590413, 19104.904475477622, 20796.584068318625, 21361.252356167537, 21476.57046859062, 21568.352949411525, 21799.0, 23066.30801075966, 23338.883305583448, 23502.529949401003, 23728.0, 5936.168936348915, 6093.0, 6132.938670613304, 6161.199044124258, 6176.073588961669, 5877.941220587803, 6076.4834989025985, 6154.169187159721, 6162.506999440034, 6173.506119510451, 5872.383399743008, 6037.547183961198, 6048.697565121726, 6107.3587273336325, 6128.938710612903, 6038.57729958903, 6070.484008859252, 6095.542834287402, 6102.084687296897, 6120.785772497963, 5369.731513424314, 5385.865353366158, 5428.809991650273, 5436.103042997901, 5437.0, 23207.767922320898, 24192.758072419227, 25667.0, 26052.60921086185, 26116.8694156528, 1745.694503461889, 2187.8687278763327, 2203.46015226269, 2224.3104637562406, 2225.432514708739, 17694.203760830795, 17910.925344479372, 17972.640547188985, 18178.63642727138, 18186.0, 1916.3867562380044, 2117.2272120676, 2158.0720290275176, 2179.259051922338, 2186.180182431588, 113010.43494782473, 143005.0, 143517.0, 143858.99999999895, 145335.0932981345, 12557.121001529806, 12894.0, 12991.415386307639, 13017.609471715863, 13073.869261307455, 16491.83508164903, 16722.58193545171, 16746.74879876803, 16815.831841681673, 16835.74746378805, 8730.388872778845, 8989.55052247398, 9051.81896362069, 9260.259179265642, 9273.258139348836, 8623.094575069654, 8637.17946795066, 8642.697505587274, 8800.559972001502, 8829.64681412749, 7051.8237044073285, 7824.982752242286, 8009.359251259885, 8066.838663226703, 8144.981877265342, 2233.2630232023394, 2242.1143648258844, 2247.6403775396184, 2257.537204872997, 2259.2770313499523, 967.9661211857551, 969.3650658818485, 995.9950200248952, 996.9003099689972, 1037.419045334615, 15510.767338489932, 16170.27233774483, 19470.902645486964, 21427.250046248617, 21547.030383632773, 817.2113910076686, 1000.8598796168542, 1005.8884932150015, 1028.5063169678597, 1049.1763965287214, 7760.689572417153, 9637.951810240904, 9945.950270248602, 11045.28205666629, 11113.166512511594, 499569.0, 509397.62409503205, 517376.0, 521791.0, 524258.0, 88292.0, 117539.0, 118111.0, 120880.0, 123276.0, 1041.0, 1189.108168873345, 1210.3585099897068, 1218.7196944702769, 1225.40567824605, 18349.81650183481, 18429.723554146967, 18572.535686607942, 18583.51331893472, 18839.058047097864, 53153.13964011241, 53976.19035714546, 54049.18926216031, 55214.895702086535, 55623.0, 73133.0, 73478.0, 73922.52154956764, 74896.0, 75600.62199689186, 8689.30485561141, 8867.320062174125, 8993.820123597361, 9103.408278461879, 9269.953650231706, 12150.878491215328, 13697.178169309873, 14059.22674252926, 14121.2233327168, 14259.572212833216, 10673.786524269315, 10865.076468500267, 10938.070264027647, 10970.396628185526, 11170.0, 10517.10604598587, 10744.892551074701, 10865.945670271914, 11031.0, 11129.777404451703, 10717.839232411676, 11008.064314533354, 11094.44527773592, 11252.0, 11479.770404592029, 8942.46345219289, 8997.955010224907, 9008.954955225181, 9023.864642030507, 9054.230390416626, 11135.164862635496, 11138.050841102937, 11185.608503702331, 11206.271592346471, 11277.71805704864], "value": 10199.112928811213, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.5233744358197, 1437.5974727076416, 1441.437839242695, 1449.5941136481786, 1458.5624312706186, 11213.0, 11395.202335836495, 11773.529058837647, 11847.822282665757, 12140.210886292407, 11174.720631984184, 11385.487653055632, 11437.428128593558, 11452.656420307383, 11469.0, 2188.245055455865, 2468.827182097254, 2566.0, 2579.1617724239604, 2584.0, 2497.425592113815, 2532.987335063322, 2549.7195308516048, 2556.143691863228, 2562.948741025178, 2503.2114883811632, 2523.684539432571, 2556.6548515950294, 2559.2322303309024, 2559.4753075619456, 2139.7539282982343, 2485.9751402486013, 2498.1880888711153, 2502.9612710725028, 2533.9239822805343, 2450.387403149213, 2495.051880285498, 2510.447701505684, 2543.0844895837486, 2554.067765265683, 11260.268082574607, 11335.263207891461, 11449.198556101077, 11449.713757156054, 11464.25482343645, 6395.424411802914, 6558.049082883003, 6579.868402631921, 6672.099266598995, 6679.76620818269, 3588.6949609283242, 3889.630485103939, 4169.395437661552, 4198.265303571863, 4235.195312890572, 1139.0033720494566, 1187.5665382135544, 1205.0, 1213.5327898759042, 1218.1107791312304, 15821.288042038135, 16112.19439028044, 16199.757003644954, 16228.10745409002, 16278.941868778491, 4522.66080043998, 5317.229001794718, 5399.5950303726595, 5529.060059789846, 5543.251661025791, 11312.434378281218, 11545.018673412855, 11550.768984620261, 11604.0, 11611.419429028514, 6378.712957916905, 6426.0, 6446.709898054599, 6452.4515416189215, 6458.806235812933, 5862.824115276549, 6016.939830601638, 6183.474404675653, 6192.535559833031, 6201.875962480725, 4146.688998325078, 4260.062786187096, 4280.636145927631, 4282.379055037002, 4302.290122129876, 1239.1016513028162, 1253.8595518961336, 1260.1997731440601, 1262.9896083133456, 1266.7593157300269, 794.1462927353118, 795.6697970342243, 813.979650508742, 818.6029775558849, 826.9462484938459, 6079.75680972765, 6082.178905847654, 6189.8452538686, 6281.8743625127245, 6362.0, 476.83042158180143, 495.52429667519584, 514.5420575687641, 561.9297587801525, 607.0802733858209, 3885.7668539887695, 4094.488188976378, 4295.22685916538, 4310.50429200646, 4395.802188901507, 14451.855481445262, 14475.855241447662, 14765.187914664797, 14774.556763297118, 14790.260486975609, 14253.714925701428, 14510.201938893251, 14670.0, 14678.85321146775, 14767.778483322758, 8028.518288902801, 8279.461834980708, 8464.576771161417, 8468.618912148844, 8473.5339556325, 6874.484413668894, 7135.286471352823, 7186.640667966685, 7193.748218812317, 7261.455390845602, 9327.860082098638, 12938.417771200131, 13194.0, 13468.461261549428, 13622.182669039494, 12171.208871423329, 13523.323833808467, 13547.525836595672, 13606.319684015958, 13983.930080349533, 8134.349252059703, 8318.292945099734, 8393.622286996977, 8421.368397370099, 8429.114942930904, 10266.897331026892, 10325.63860264887, 10374.377537347482, 10400.219983501116, 10416.062554370143, 8747.256483198998, 8779.385443018928, 8806.471611703319, 8849.336299777413, 8876.423032502867, 7148.213696493278, 7362.7423040193335, 8508.574571271536, 8569.442986205877, 8647.524386158822, 6594.406503414716, 7475.2151024141685, 7779.922200778146, 7971.561564114029, 8063.032436107705, 5936.0, 5997.460228579449, 6039.939600603938, 6040.6979651018155, 6201.875962480635, 5279.762410691605, 6042.235826669577, 6118.143459915526, 6165.599236049642, 6233.75064997395], "value": 5187.125096876647, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.62482581573175, 382.0315500206976, 386.72735721316474, 413.8572192593554, 414.33912908910276, 4534.977325113374, 6011.0, 6204.293819199723, 6383.872322553546, 6383.936160638392, 5305.885763989557, 5606.579506537016, 6046.12331211974, 6176.313303712888, 6195.504359651229, 4513.796879140446, 4519.6441067679725, 4534.9546504534865, 4550.180967425862, 4559.452865656126, 3627.5828279747902, 4100.5489396166395, 4506.684532082739, 4518.570735780112, 4531.456225252974, 4033.9394909076386, 4360.803763830635, 4438.868088637393, 4450.599446049855, 4482.417285752864, 3856.305864944323, 4482.61897738691, 4487.339684316791, 4492.326151077332, 4501.549845015505, 3721.0, 3816.969418257083, 3826.885193444201, 3842.426543314716, 3846.2307538492414, 5167.354080739908, 6013.248343957005, 6032.698365081728, 6033.944059789563, 6036.96981515094, 5317.335333083364, 5338.359396872362, 5344.839654810362, 5353.357597088336, 5371.328583927009, 5246.554042906319, 5733.799317023884, 5989.850253743648, 6570.868582628368, 6672.866542669168, 3421.623621401644, 4371.213181627306, 4385.386045953569, 4400.7579583122915, 4474.194644963905, 6148.84627884302, 6524.478041756695, 6683.53215274931, 7032.648367581601, 7158.391536719385, 3647.7446578739764, 4246.893827654271, 4389.495208051051, 4389.736615803062, 4390.143921935192, 3700.9829642844716, 4490.326451032372, 4493.303537951622, 4506.0, 4515.593596576324, 4388.27593447084, 5168.948310516922, 5318.414974352895, 5328.8401347959625, 5342.866428339323, 5468.398476167618, 6629.3370662934285, 6673.099131617267, 6720.865582688319, 6722.66386680664, 1378.4348417148922, 1513.954581362561, 1529.8776097912141, 1546.1418912503668, 1548.5276990518, 857.5069335132199, 942.6229508196776, 963.5856581669809, 998.9450580218157, 1010.4442556593845, 5263.815766448156, 5535.363433205233, 5591.804286849941, 6280.0, 6345.36546345371, 5916.349201587908, 6789.8796698545175, 6927.168739751161, 6932.376086152271, 6960.825979350557, 5277.841664749987, 6310.337414571404, 6324.861524925557, 6420.357964203542, 6427.453666438311, 3338.9332213356083, 3583.8387272572795, 3606.062423769839, 3633.927321453556, 3649.2701459708164, 6725.8654826903185, 7051.576905385693, 7091.787246382617, 7136.464765142531, 7137.536060156073, 5649.293838270216, 6449.516286278548, 7076.893846592306, 7119.608421536762, 7135.429165666734, 6904.344087311697, 7085.397741192057, 7225.385842203366, 7225.9522369256165, 7239.8552028960175, 6895.827604309932, 7031.332023457763, 7039.788806335818, 7045.260247674126, 7047.224805271314, 5557.666160121623, 5974.342822289632, 6608.2575731071065, 6854.588724676535, 6880.965595171992, 6430.678466076584, 7218.89171662436, 7225.747098851515, 7249.782506524813, 7304.488685791944, 3669.8348574314755, 3716.5168528091176, 4192.323070771716, 4216.746995180299, 4411.051623900877, 5720.971395142998, 6776.96611516959, 6948.791536253921, 7004.754833580801, 7069.293070692889, 6712.765053223114, 6730.562513436611, 6775.390214880685, 6788.321167883172, 6794.728210871707, 5253.894922101613, 5507.421720719267, 6203.8596684143895, 6705.765298214539, 6795.694193761194, 5757.553039958085, 6053.636781793107, 6202.751889924533, 6422.3577642235405, 6442.452391546771, 5359.383670877743, 6549.705263263069, 6704.513652900562, 6758.080313418107, 6780.762673306411, 5872.913511000478, 6373.5857169283845, 6517.047044246502, 6561.737530498726, 6583.275839657538], "value": 4746.046617632348, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.3902529185796, 812.8130529978101, 838.7064527415405, 847.245951103518, 848.2154007543021, 16413.34346626135, 16864.49406517805, 16879.49361519153, 18579.81420185798, 19139.33012344568, 17875.821241787577, 17978.730319045193, 18391.172397242124, 18622.72065919009, 18793.90603046986, 971.5142428785607, 992.1566668331897, 995.5520015992814, 997.7655251016006, 1002.7443002034471, 1000.5447521377756, 1002.3134153105146, 1003.5985605757683, 1004.4726518577733, 1006.0593345222204, 951.8524628682566, 1033.9844902326472, 1035.969210635419, 1037.8339465685492, 1039.5270152080823, 1021.5147804792738, 1033.188946676855, 1042.2235434601232, 1045.2630895218929, 1046.5133712823535, 747.7794050754986, 780.5862892666839, 781.8240895798457, 788.4874831359605, 791.9722809701633, 16831.495055148363, 16835.74746378805, 16935.237914293764, 17038.48884533466, 17058.14709264532, 17217.05306208158, 17240.913795431068, 17471.340222678824, 17592.12039398025, 17613.7357939631, 14227.644308892257, 14377.209253491053, 14419.567412977627, 14599.562013139623, 14617.634559136, 3159.6208454985317, 3490.1100219444133, 3572.5534308211472, 3579.6420357964257, 3582.731295152848, 108061.45969270103, 125527.0, 127356.0, 129038.0, 129676.0, 13468.5286014989, 13563.25402102874, 13663.863361366459, 13729.244891531062, 13778.0, 19779.703304450446, 21822.345329640135, 22681.659775103388, 25335.493290134094, 25718.87140564285, 7874.960625196837, 7976.441649084624, 7993.120756716757, 8048.839023219502, 8051.0, 3855.8264878080554, 3896.2402331545645, 3934.8229329680235, 3938.6455219030427, 3941.7832019239218, 3556.3776339140545, 4208.389783481378, 4274.123804620045, 4350.934735978963, 4352.7823608819435, 930.5300823084299, 941.4257303045136, 942.9858552121724, 948.9003654616304, 951.6764300137847, 564.858785303674, 634.5970308853819, 649.7076315657878, 654.2574178307655, 667.5027104806853, 8866.113388661082, 10222.846657300146, 10482.213833962483, 10817.621383251706, 12253.081018923616, 637.5409705012477, 639.4117411980989, 652.738904438219, 665.3845193196274, 680.9523333366617, 3901.7463864848696, 4275.657947364203, 5572.554195664337, 5665.235193248941, 5752.482276595127, 70419.0, 88612.5569372149, 97047.51476242715, 97861.02138978662, 98839.50580247052, 69389.0, 80833.0, 82499.0, 86015.4089984149, 87998.0, 74213.628931855, 74320.19637937938, 75405.0, 75500.0, 76463.0, 11249.550017999189, 11338.0, 11480.712982175512, 11582.478788454708, 11739.002184814386, 30057.248568785955, 37432.438513421774, 41625.0, 41780.58219417888, 45311.77344113258, 55353.44646553483, 70063.0, 74192.0, 74495.0, 77606.28378006743, 14075.296235188405, 17279.22243499071, 17309.221085051453, 17592.120393980505, 17699.203535841174, 18129.546761330545, 18701.90649046746, 18810.153543090866, 18829.90585047066, 19076.809231908057, 17024.74462883033, 17620.73568896493, 17701.0, 17769.111544422467, 17905.19426625779, 14274.429022838971, 16694.24875880564, 17506.86205396645, 17955.551111222325, 18239.270429183216, 16632.833671663127, 16715.414960476326, 16834.57913552171, 17023.659526809643, 17148.0, 9959.0, 11020.118390528578, 11275.830862537232, 11308.095352371955, 11448.026917711755, 13849.723005539632, 14029.368678409292, 14066.085704429179, 14104.435822566982, 14128.646783830487], "value": 8335.776253503776, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 116.0, 114.0, 115.0, 111.0, 115.0, 115.0, 103.0, 170.0, 171.0], "value": 123.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 97.0, 170.0, 145.0, 151.0, 142.0, 98.0, 157.0, 145.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 203.0, 199.0, 243.0, 250.0, 213.0, 196.0, 208.0, 272.0, 263.0], "value": 225.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 82.0, 73.0, 53.0, 51.0, 54.0, 92.0, 92.0, 86.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 88.0, 84.0, 72.0, 71.0, 84.0, 69.0, 70.0, 100.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 89.0, 88.0, 89.0, 125.0, 83.0, 126.0, 137.0, 96.0, 89.0], "value": 105.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 97.0, 106.0, 96.0, 97.0, 96.0, 96.0, 98.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 92.0, 92.0, 92.0, 92.0, 91.0, 93.0, 93.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 70.0, 71.0, 63.0, 50.0, 53.0, 51.0, 55.0, 58.0, 65.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 62.0, 56.0, 59.0, 60.0, 59.0, 58.0, 55.0, 49.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 85.0, 87.0, 94.0, 86.0, 73.0, 81.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 154.0, 151.0, 162.0, 160.0, 165.0, 154.0, 191.0, 140.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 220.0, 188.0, 189.0, 191.0, 188.0, 190.0, 230.0, 190.0, 182.0], "value": 197.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 98.0, 103.0, 79.0, 63.0, 96.0, 100.0, 97.0, 103.0, 67.0], "value": 87.9, "unit": "score"}]}]} 17:58:52 INFO - Return code: 0 17:58:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:58:52 INFO - # TBPL SUCCESS # 17:58:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:58:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:58:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:58:52 INFO - 2017-05-19 17:58:52.952 screenresolution[1928:18098] starting screenresolution argv=screenresolution get 17:58:52 INFO - 2017-05-19 17:58:52.956 screenresolution[1928:18098] Display 0: 1600x1200x32@60 17:58:52 INFO - 2017-05-19 17:58:52.962 screenresolution[1929:18101] starting screenresolution argv=screenresolution list 17:58:52 INFO - Available Modes on Display 0 17:58:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:58:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:58:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:58:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:58:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:58:53 INFO - Intel Iris: 17:58:53 INFO - Chipset Model: Intel Iris 17:58:53 INFO - Type: GPU 17:58:53 INFO - Bus: Built-In 17:58:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:58:53 INFO - Vendor: Intel (0x8086) 17:58:53 INFO - Device ID: 0x0a2e 17:58:53 INFO - Revision ID: 0x0009 17:58:53 INFO - Displays: 17:58:53 INFO - Display: 17:58:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:58:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:58:53 INFO - Display Serial Number: 1600x1200 60 17:58:53 INFO - Main Display: Yes 17:58:53 INFO - Mirror: Off 17:58:53 INFO - Online: Yes 17:58:53 INFO - Rotation: Supported 17:58:53 INFO - Return code: 0 17:58:53 INFO - Running post-action listener: _resource_record_post_action 17:58:53 INFO - [mozharness: 2017-05-20 00:58:53.203363Z] Finished run-tests step (success) 17:58:53 INFO - Running post-run listener: _resource_record_post_run 17:58:53 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 1171456; Write bytes: 566263808; Read time: 15; Write time: 1966 17:58:53 INFO - TinderboxPrint: CPU usage
54.1% 17:58:53 INFO - TinderboxPrint: I/O read bytes / time
1,171,456 / 15 17:58:53 INFO - TinderboxPrint: I/O write bytes / time
566,263,808 / 1,966 17:58:53 INFO - TinderboxPrint: CPU idle
2,195.2 (45.9%) 17:58:53 INFO - TinderboxPrint: CPU system
966.2 (20.2%) 17:58:53 INFO - TinderboxPrint: CPU user
1,624.3 (33.9%) 17:58:53 INFO - TinderboxPrint: Swap in / out
246,960,128 / 0 17:58:53 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 236065280; Write bytes: 228626432; Read time: 16898; Write time: 402 17:58:53 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 1171456; Write bytes: 319381504; Read time: 15; Write time: 1519 17:58:53 INFO - Running post-run listener: _upload_blobber_files 17:58:53 INFO - Blob upload gear active. 17:58:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:58:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:58:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:58:54 INFO - (blobuploader) - INFO - Open directory for files ... 17:58:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:58:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:58:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:54 INFO - (blobuploader) - INFO - Done attempting. 17:58:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:58:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:58:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:55 INFO - (blobuploader) - INFO - Done attempting. 17:58:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:58:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:58:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:56 INFO - (blobuploader) - INFO - Done attempting. 17:58:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:58:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:58:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:59 INFO - (blobuploader) - INFO - Done attempting. 17:58:59 INFO - (blobuploader) - INFO - Iteration through files over. 17:58:59 INFO - Return code: 0 17:58:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:58:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:58:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8199015033342fe6fbc256bad02b8408236c1493abd922e04bec09b83c3c64f299a3e76b66430239d687ae308dcc7d3d4c10eea83f2f1d093e7e3feaaa12108f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963e949e321357721a89267acf39cde57a31c7974c3b89d2ddd1591708fced13a51694ac616cdeecbe2e309bdc00a67021543719ad331994648122306762fd98", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fe2e0c63370c93fd9705035a5ba7b0c8af8db2c05f23849bc569f4852e973173fd1151ffde189d326a751d108f2b2784052e19daf98c1159f800833b09a11c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bceb949b7f20ea4dbaff5b7f9e53774da2ff0151379fec1b8d58bcf347268adf0e62aab27af371c8334ee1815a1cca6af62717c7b71a57ca6e9887fca821f56"} 17:58:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:58:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:58:59 INFO - Contents: 17:58:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8199015033342fe6fbc256bad02b8408236c1493abd922e04bec09b83c3c64f299a3e76b66430239d687ae308dcc7d3d4c10eea83f2f1d093e7e3feaaa12108f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963e949e321357721a89267acf39cde57a31c7974c3b89d2ddd1591708fced13a51694ac616cdeecbe2e309bdc00a67021543719ad331994648122306762fd98", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fe2e0c63370c93fd9705035a5ba7b0c8af8db2c05f23849bc569f4852e973173fd1151ffde189d326a751d108f2b2784052e19daf98c1159f800833b09a11c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bceb949b7f20ea4dbaff5b7f9e53774da2ff0151379fec1b8d58bcf347268adf0e62aab27af371c8334ee1815a1cca6af62717c7b71a57ca6e9887fca821f56"} 17:58:59 INFO - Running post-run listener: copy_logs_to_upload_dir 17:58:59 INFO - Copying logs to upload dir... 17:58:59 INFO - mkdir: /builds/slave/test/build/upload/logs 17:58:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.626983 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-19 17:58:59.154630) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 17:58:59.157929) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtXSW8jBkX/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8199015033342fe6fbc256bad02b8408236c1493abd922e04bec09b83c3c64f299a3e76b66430239d687ae308dcc7d3d4c10eea83f2f1d093e7e3feaaa12108f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963e949e321357721a89267acf39cde57a31c7974c3b89d2ddd1591708fced13a51694ac616cdeecbe2e309bdc00a67021543719ad331994648122306762fd98", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fe2e0c63370c93fd9705035a5ba7b0c8af8db2c05f23849bc569f4852e973173fd1151ffde189d326a751d108f2b2784052e19daf98c1159f800833b09a11c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bceb949b7f20ea4dbaff5b7f9e53774da2ff0151379fec1b8d58bcf347268adf0e62aab27af371c8334ee1815a1cca6af62717c7b71a57ca6e9887fca821f56"} build_url:https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011707 build_url: 'https://queue.taskcluster.net/v1/task/ItzSNHnESKixSdmZVb021w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8199015033342fe6fbc256bad02b8408236c1493abd922e04bec09b83c3c64f299a3e76b66430239d687ae308dcc7d3d4c10eea83f2f1d093e7e3feaaa12108f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963e949e321357721a89267acf39cde57a31c7974c3b89d2ddd1591708fced13a51694ac616cdeecbe2e309bdc00a67021543719ad331994648122306762fd98", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fe2e0c63370c93fd9705035a5ba7b0c8af8db2c05f23849bc569f4852e973173fd1151ffde189d326a751d108f2b2784052e19daf98c1159f800833b09a11c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bceb949b7f20ea4dbaff5b7f9e53774da2ff0151379fec1b8d58bcf347268adf0e62aab27af371c8334ee1815a1cca6af62717c7b71a57ca6e9887fca821f56"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 17:58:59.191789) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 17:58:59.192144) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtXSW8jBkX/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.kaFcKOdnAr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005251 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 17:58:59.241595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 17:58:59.241907) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 17:58:59.242309) ========= ========= Total master_lag: 0.18 =========