builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0012 starttime: 1497961778.51 results: success (0) buildid: 20170620032056 builduid: 06075013cfff4d078c48064a89c9e7f1 revision: d4304ddb6413bd690f757c1eba447c8136887bf9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.512836) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.513299) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.513579) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.539151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.539550) ========= 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.LTcQZCqhmt/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.74gmgnLhhf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-06-20 05:29:38-- 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% 92.2M=0s 2017-06-20 05:29:38 (92.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.682613) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.682956) ========= 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.LTcQZCqhmt/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.74gmgnLhhf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.029145 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:38.731971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 05:29:38.732325) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d4304ddb6413bd690f757c1eba447c8136887bf9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d4304ddb6413bd690f757c1eba447c8136887bf9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LTcQZCqhmt/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.74gmgnLhhf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-06-20 05:29:38,825 truncating revision to first 12 chars 2017-06-20 05:29:38,825 Setting DEBUG logging. 2017-06-20 05:29:38,825 attempt 1/10 2017-06-20 05:29:38,825 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d4304ddb6413?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-20 05:29:40,467 unpacking tar archive at: mozilla-beta-d4304ddb6413/testing/mozharness/ program finished with exit code 0 elapsedTime=1.899049 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 05:29:40.650202) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:40.650563) ========= script_repo_revision: d4304ddb6413bd690f757c1eba447c8136887bf9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:40.651006) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:40.651274) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 05:29:40.667840) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 05:29:40.668183) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LTcQZCqhmt/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.74gmgnLhhf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:29:40 INFO - MultiFileLogger online at 20170620 05:29:40 in /builds/slave/test 05:29:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:29:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:40 INFO - {'append_to_log': False, 05:29:40 INFO - 'base_work_dir': '/builds/slave/test', 05:29:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:29:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:40 INFO - 'branch': 'Mozilla-Beta', 05:29:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:40 INFO - 'config_files': ('talos/mac_config.py',), 05:29:40 INFO - 'default_actions': ('clobber', 05:29:40 INFO - 'read-buildbot-config', 05:29:40 INFO - 'download-and-extract', 05:29:40 INFO - 'populate-webroot', 05:29:40 INFO - 'create-virtualenv', 05:29:40 INFO - 'install', 05:29:40 INFO - 'setup-mitmproxy', 05:29:40 INFO - 'run-tests'), 05:29:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:40 INFO - 'download_minidump_stackwalk': True, 05:29:40 INFO - 'download_symbols': 'ondemand', 05:29:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:29:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:29:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:29:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:29:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:40 INFO - 'gecko_profile': False, 05:29:40 INFO - 'gecko_profile_interval': 0, 05:29:40 INFO - 'installer_path': 'installer.exe', 05:29:40 INFO - 'log_level': 'info', 05:29:40 INFO - 'log_name': 'talos', 05:29:40 INFO - 'log_to_console': True, 05:29:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:29:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:29:40 INFO - 'opt_config_files': (), 05:29:40 INFO - 'pip_index': False, 05:29:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:40 INFO - 'cmd': ('bash', 05:29:40 INFO - '-c', 05:29:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:40 INFO - 'enabled': True, 05:29:40 INFO - 'halt_on_failure': False, 05:29:40 INFO - 'name': 'check_screen_resolution'},), 05:29:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:40 INFO - 'cmd': ('bash', 05:29:40 INFO - '-c', 05:29:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:40 INFO - 'enabled': True, 05:29:40 INFO - 'halt_on_failure': False, 05:29:40 INFO - 'name': 'check_screen_resolution'},), 05:29:40 INFO - 'run_cmd_checks_enabled': True, 05:29:40 INFO - 'suite': 'dromaeojs-e10s', 05:29:40 INFO - 'system_bits': '32', 05:29:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:40 INFO - 'title': 't-yosemite-r7-0012', 05:29:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:40 INFO - 'use_talos_json': True, 05:29:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:40 INFO - 'work_dir': 'build'} 05:29:40 INFO - [mozharness: 2017-06-20 12:29:40.865782Z] Running clobber step. 05:29:40 INFO - Running pre-action listener: _resource_record_pre_action 05:29:40 INFO - Running main action method: clobber 05:29:40 INFO - rmtree: /builds/slave/test/build 05:29:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:43 INFO - Running post-action listener: _resource_record_post_action 05:29:43 INFO - [mozharness: 2017-06-20 12:29:43.287796Z] Finished clobber step (success) 05:29:43 INFO - [mozharness: 2017-06-20 12:29:43.287932Z] Running read-buildbot-config step. 05:29:43 INFO - Running pre-action listener: _resource_record_pre_action 05:29:43 INFO - Running main action method: read_buildbot_config 05:29:43 INFO - Using buildbot properties: 05:29:43 INFO - { 05:29:43 INFO - "project": "", 05:29:43 INFO - "product": "firefox", 05:29:43 INFO - "script_repo_revision": "production", 05:29:43 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 05:29:43 INFO - "repository": "", 05:29:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 05:29:43 INFO - "buildid": "20170620032056", 05:29:43 INFO - "pgo_build": "False", 05:29:43 INFO - "basedir": "/builds/slave/test", 05:29:43 INFO - "buildnumber": 14, 05:29:43 INFO - "slavename": "t-yosemite-r7-0012", 05:29:43 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:29:43 INFO - "platform": "yosemite_r7_devedition", 05:29:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 05:29:43 INFO - "branch": "mozilla-beta", 05:29:43 INFO - "repo_path": "releases/mozilla-beta", 05:29:43 INFO - "revision": "d4304ddb6413bd690f757c1eba447c8136887bf9", 05:29:43 INFO - "stage_platform": "macosx64-devedition", 05:29:43 INFO - "builduid": "06075013cfff4d078c48064a89c9e7f1", 05:29:43 INFO - "slavebuilddir": "test" 05:29:43 INFO - } 05:29:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 05:29:43 INFO - Running post-action listener: _resource_record_post_action 05:29:43 INFO - [mozharness: 2017-06-20 12:29:43.291345Z] Finished read-buildbot-config step (success) 05:29:43 INFO - [mozharness: 2017-06-20 12:29:43.291476Z] Running download-and-extract step. 05:29:43 INFO - Running pre-action listener: _resource_record_pre_action 05:29:43 INFO - Running main action method: download_and_extract 05:29:43 INFO - mkdir: /builds/slave/test/build/tests 05:29: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')]} 05:29:43 INFO - trying https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 05:29:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 05:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 05:29:44 INFO - Downloaded 1290 bytes. 05:29:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 05:29:44 INFO - Using the following test package requirements: 05:29:44 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 05:29:44 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 05:29:44 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 05:29:44 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 05:29:44 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:29:44 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 05:29:44 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 05:29:44 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 05:29:44 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 05:29:44 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 05:29:44 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 05:29:44 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 05:29:44 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 05:29:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 05:29:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 05:29:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 05:29:46 INFO - Content-Length response header: 59545061 05:29:46 INFO - Bytes received: 59545061 05:30:04 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:30:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 05:30:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 05:30:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 05:30:06 INFO - Content-Length response header: 59545061 05:30:06 INFO - Bytes received: 59545061 05:30:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 05:30:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 05:30:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 05:30:24 INFO - Content-Length response header: 14454470 05:30:24 INFO - Bytes received: 14454470 05:30:25 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')]} 05:30:25 INFO - trying https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 05:30:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 05:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 05:30:27 INFO - Downloaded 66852410 bytes. 05:30:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 05:30:27 INFO - mkdir: /builds/slave/test/properties 05:30:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:27 INFO - Contents: 05:30:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 05:30:27 INFO - Running post-action listener: _resource_record_post_action 05:30:27 INFO - Running post-action listener: set_extra_try_arguments 05:30:27 INFO - [mozharness: 2017-06-20 12:30:27.742922Z] Finished download-and-extract step (success) 05:30:27 INFO - [mozharness: 2017-06-20 12:30:27.743078Z] Running populate-webroot step. 05:30:27 INFO - Running pre-action listener: _resource_record_pre_action 05:30:27 INFO - Running main action method: populate_webroot 05:30:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:30:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:30:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:30:27 INFO - '--tppagecycles', 05:30:27 INFO - '7'], 05:30:27 INFO - 'tests': ['tsvgm']}}, 05:30:27 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:30:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['damp', 'tps']}, 05:30:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'tests': ['damp', 'tps']}, 05:30:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['dromaeo_dom']}, 05:30:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:30:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:30:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:30:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['a11yr', 05:30:27 INFO - 'ts_paint', 05:30:27 INFO - 'tpaint', 05:30:27 INFO - 'sessionrestore', 05:30:27 INFO - 'sessionrestore_no_auto_restore', 05:30:27 INFO - 'tabpaint']}, 05:30:27 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:27 INFO - 'ts_paint', 05:30:27 INFO - 'tpaint', 05:30:27 INFO - 'sessionrestore', 05:30:27 INFO - 'sessionrestore_no_auto_restore', 05:30:27 INFO - 'tabpaint']}, 05:30:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:30:27 INFO - 'ts_paint', 05:30:27 INFO - 'tpaint', 05:30:27 INFO - 'sessionrestore', 05:30:27 INFO - 'sessionrestore_no_auto_restore', 05:30:27 INFO - 'tabpaint']}, 05:30:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:30:27 INFO - 'ts_paint', 05:30:27 INFO - 'tpaint', 05:30:27 INFO - 'sessionrestore', 05:30:27 INFO - 'sessionrestore_no_auto_restore', 05:30:27 INFO - 'tabpaint']}, 05:30:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['a11yr', 05:30:27 INFO - 'ts_paint', 05:30:27 INFO - 'tpaint', 05:30:27 INFO - 'sessionrestore', 05:30:27 INFO - 'sessionrestore_no_auto_restore', 05:30:27 INFO - 'tabpaint']}, 05:30:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['a11yr', 05:30:27 INFO - 'ts_paint', 05:30:27 INFO - 'tpaint', 05:30:27 INFO - 'sessionrestore', 05:30:27 INFO - 'sessionrestore_no_auto_restore', 05:30:27 INFO - 'tabpaint']}, 05:30:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:30:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:30:27 INFO - 'bloom_basic_ref']}, 05:30:27 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:30:27 INFO - 'talos_options': ['--mitmproxy', 05:30:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:30:27 INFO - '--firstNonBlankPaint'], 05:30:27 INFO - 'tests': ['quantum_pageload_google', 05:30:27 INFO - 'quantum_pageload_youtube', 05:30:27 INFO - 'quantum_pageload_amazon', 05:30:27 INFO - 'quantum_pageload_facebook']}, 05:30:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['tsvgx', 05:30:27 INFO - 'tsvgr_opacity', 05:30:27 INFO - 'tart', 05:30:27 INFO - 'tscrollx', 05:30:27 INFO - 'cart', 05:30:27 INFO - 'tsvg_static']}, 05:30:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:27 INFO - 'tsvgr_opacity', 05:30:27 INFO - 'tart', 05:30:27 INFO - 'tscrollx', 05:30:27 INFO - 'cart', 05:30:27 INFO - 'tsvg_static']}, 05:30:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'talos_options': ['--disable-e10s'], 05:30:27 INFO - 'tests': ['tp5o']}, 05:30:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:30:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'talos_options': ['--disable-e10s', 05:30:27 INFO - '--xperf_path', 05:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:27 INFO - 'tests': ['tp5n']}, 05:30:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:30:27 INFO - 'talos_options': ['--xperf_path', 05:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:27 INFO - 'tests': ['tp5n']}}, 05:30:27 INFO - 'talos.zip': {'path': '', 05:30:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:30:27 INFO - Running post-action listener: _resource_record_post_action 05:30:27 INFO - [mozharness: 2017-06-20 12:30:27.763717Z] Finished populate-webroot step (success) 05:30:27 INFO - [mozharness: 2017-06-20 12:30:27.763828Z] Running create-virtualenv step. 05:30:27 INFO - Running pre-action listener: _resource_record_pre_action 05:30:27 INFO - Running main action method: create_virtualenv 05:30:27 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:27 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 05:30:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:30:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:30:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:27 INFO - Using real prefix '/tools/python27' 05:30:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:30:31 INFO - Installing pip.................done. 05:30:31 INFO - Return code: 0 05:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:30:31 INFO - Reading from file tmpfile_stdout 05:30:31 INFO - Output received: 05:30:31 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) 05:30:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:30:31 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')]} 05:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:31 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')]} 05:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:31 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:31 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 05:30:31 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 05:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:31 INFO - 'HOME': '/Users/cltbld', 05:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:31 INFO - 'LOGNAME': 'cltbld', 05:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:31 INFO - 'MOZ_NO_REMOTE': '1', 05:30:31 INFO - 'NO_EM_RESTART': '1', 05:30:31 INFO - 'PAGER': '/bin/cat', 05:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:31 INFO - 'PWD': '/builds/slave/test', 05:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:31 INFO - 'SHELL': '/bin/bash', 05:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:31 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:31 INFO - 'USER': 'cltbld', 05:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:31 INFO - 'XPC_FLAGS': '0x0', 05:30:31 INFO - 'XPC_SERVICE_NAME': '0', 05:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:31 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 05:30:31 INFO - Cleaning up... 05:30:32 INFO - Return code: 0 05:30:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:30:32 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')]} 05:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:32 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')]} 05:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:32 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:32 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 05:30:32 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 05:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:32 INFO - 'HOME': '/Users/cltbld', 05:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:32 INFO - 'LOGNAME': 'cltbld', 05:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:32 INFO - 'MOZ_NO_REMOTE': '1', 05:30:32 INFO - 'NO_EM_RESTART': '1', 05:30:32 INFO - 'PAGER': '/bin/cat', 05:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:32 INFO - 'PWD': '/builds/slave/test', 05:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:32 INFO - 'SHELL': '/bin/bash', 05:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:32 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:32 INFO - 'USER': 'cltbld', 05:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:32 INFO - 'XPC_FLAGS': '0x0', 05:30:32 INFO - 'XPC_SERVICE_NAME': '0', 05:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:32 INFO - Downloading/unpacking psutil>=3.1.1 05:30:32 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 05:30:32 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 05:30:32 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 05:30:32 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 05:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:34 INFO - Installing collected packages: psutil 05:30:34 INFO - Running setup.py install for psutil 05:30:34 INFO - building 'psutil._psutil_osx' extension 05:30:34 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 05:30:34 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 05:30:34 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 05:30:35 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 05:30:35 INFO - building 'psutil._psutil_posix' extension 05:30:35 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 05:30:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:30:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:30:35 INFO - ^ 05:30:35 INFO - 1 warning generated. 05:30:35 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 05:30:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:35 INFO - Successfully installed psutil 05:30:35 INFO - Cleaning up... 05:30:35 INFO - Return code: 0 05:30:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30: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')]} 05:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:36 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:36 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 05:30:36 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 05:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:36 INFO - 'HOME': '/Users/cltbld', 05:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:36 INFO - 'LOGNAME': 'cltbld', 05:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:36 INFO - 'MOZ_NO_REMOTE': '1', 05:30:36 INFO - 'NO_EM_RESTART': '1', 05:30:36 INFO - 'PAGER': '/bin/cat', 05:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:36 INFO - 'PWD': '/builds/slave/test', 05:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:36 INFO - 'SHELL': '/bin/bash', 05:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:36 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:36 INFO - 'USER': 'cltbld', 05:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:36 INFO - 'XPC_FLAGS': '0x0', 05:30:36 INFO - 'XPC_SERVICE_NAME': '0', 05:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:30:36 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 05:30:36 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 05:30:36 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 05:30:36 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 05:30:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:30:38 INFO - Installing collected packages: mozsystemmonitor 05:30:38 INFO - Running setup.py install for mozsystemmonitor 05:30:38 INFO - Successfully installed mozsystemmonitor 05:30:38 INFO - Cleaning up... 05:30:38 INFO - Return code: 0 05:30:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30: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')]} 05:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30: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', '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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:30: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 jsonschema==2.5.1 05:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:38 INFO - 'HOME': '/Users/cltbld', 05:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:38 INFO - 'LOGNAME': 'cltbld', 05:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:38 INFO - 'MOZ_NO_REMOTE': '1', 05:30:38 INFO - 'NO_EM_RESTART': '1', 05:30:38 INFO - 'PAGER': '/bin/cat', 05:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:38 INFO - 'PWD': '/builds/slave/test', 05:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:38 INFO - 'SHELL': '/bin/bash', 05:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:38 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:38 INFO - 'USER': 'cltbld', 05:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:38 INFO - 'XPC_FLAGS': '0x0', 05:30:38 INFO - 'XPC_SERVICE_NAME': '0', 05:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:39 INFO - Downloading/unpacking jsonschema==2.5.1 05:30: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 05:30: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 05:30: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 05:30: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 05:30:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:40 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 05:30:40 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 05:30:40 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 05:30:40 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 05:30:40 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:40 INFO - warning: no files found matching '*.txt' 05:30:40 INFO - no previously-included directories found matching 'build' 05:30:40 INFO - no previously-included directories found matching 'dist' 05:30:40 INFO - no previously-included directories found matching '.git*' 05:30:40 INFO - Installing collected packages: jsonschema, functools32 05:30:40 INFO - Running setup.py install for functools32 05:30:41 INFO - warning: no files found matching '*.txt' 05:30:41 INFO - no previously-included directories found matching 'build' 05:30:41 INFO - no previously-included directories found matching 'dist' 05:30:41 INFO - no previously-included directories found matching '.git*' 05:30:41 INFO - Successfully installed jsonschema functools32 05:30:41 INFO - Cleaning up... 05:30:41 INFO - Return code: 0 05:30:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30: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')]} 05:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30: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', '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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:30: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 functools32==3.2.3-2 05:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:41 INFO - 'HOME': '/Users/cltbld', 05:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:41 INFO - 'LOGNAME': 'cltbld', 05:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:41 INFO - 'MOZ_NO_REMOTE': '1', 05:30:41 INFO - 'NO_EM_RESTART': '1', 05:30:41 INFO - 'PAGER': '/bin/cat', 05:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:41 INFO - 'PWD': '/builds/slave/test', 05:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:41 INFO - 'SHELL': '/bin/bash', 05:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:41 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:41 INFO - 'USER': 'cltbld', 05:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:41 INFO - 'XPC_FLAGS': '0x0', 05:30:41 INFO - 'XPC_SERVICE_NAME': '0', 05:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:41 INFO - Cleaning up... 05:30:41 INFO - Return code: 0 05:30:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30: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')]} 05:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30: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', '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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:30: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 blobuploader==1.2.4 05:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:41 INFO - 'HOME': '/Users/cltbld', 05:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:41 INFO - 'LOGNAME': 'cltbld', 05:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:41 INFO - 'MOZ_NO_REMOTE': '1', 05:30:41 INFO - 'NO_EM_RESTART': '1', 05:30:41 INFO - 'PAGER': '/bin/cat', 05:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:41 INFO - 'PWD': '/builds/slave/test', 05:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:41 INFO - 'SHELL': '/bin/bash', 05:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:41 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:41 INFO - 'USER': 'cltbld', 05:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:41 INFO - 'XPC_FLAGS': '0x0', 05:30:41 INFO - 'XPC_SERVICE_NAME': '0', 05:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:30: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 05:30: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 05:30: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 05:30: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 05:30:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30: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 05:30: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 05:30: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 05:30: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 05:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30: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 05:30: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 05:30: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 05:30: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 05:30:43 INFO - Downloading docopt-0.6.1.tar.gz 05:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:44 INFO - Running setup.py install for blobuploader 05:30:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:44 INFO - Running setup.py install for requests 05:30:44 INFO - Running setup.py install for docopt 05:30:44 INFO - Successfully installed blobuploader requests docopt 05:30:44 INFO - Cleaning up... 05:30:44 INFO - Return code: 0 05:30:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:44 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')]} 05:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:44 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')]} 05:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:44 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:44 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 05:30:44 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 05:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:44 INFO - 'HOME': '/Users/cltbld', 05:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:44 INFO - 'LOGNAME': 'cltbld', 05:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:44 INFO - 'MOZ_NO_REMOTE': '1', 05:30:44 INFO - 'NO_EM_RESTART': '1', 05:30:44 INFO - 'PAGER': '/bin/cat', 05:30:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:44 INFO - 'PWD': '/builds/slave/test', 05:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:44 INFO - 'SHELL': '/bin/bash', 05:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:44 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:44 INFO - 'USER': 'cltbld', 05:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:44 INFO - 'XPC_FLAGS': '0x0', 05:30:44 INFO - 'XPC_SERVICE_NAME': '0', 05:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-7vMn8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Qd6Wcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-lICzpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-rR4myu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-uKvlFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-gT6X2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Uq8hT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-m579Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-YfIjfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-YN67sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-HLCmlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-prJ3pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5UoI9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-d48VBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ZLiN9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BTHcN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:46 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-EDgajW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:46 INFO - Running setup.py install for manifestparser 05:30:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:47 INFO - Running setup.py install for mozcrash 05:30:47 INFO - Running setup.py install for mozdebug 05:30:47 INFO - Running setup.py install for mozdevice 05:30:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:47 INFO - Running setup.py install for mozfile 05:30:47 INFO - Running setup.py install for mozhttpd 05:30:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:47 INFO - Running setup.py install for mozinfo 05:30:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:47 INFO - Running setup.py install for mozInstall 05:30:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Running setup.py install for mozleak 05:30:48 INFO - Running setup.py install for mozlog 05:30:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Running setup.py install for moznetwork 05:30:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Running setup.py install for mozprocess 05:30:48 INFO - Running setup.py install for mozprofile 05:30:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:48 INFO - Running setup.py install for mozrunner 05:30:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:49 INFO - Running setup.py install for mozscreenshot 05:30:49 INFO - Running setup.py install for moztest 05:30:49 INFO - Running setup.py install for mozversion 05:30:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:49 INFO - Cleaning up... 05:30:49 INFO - Return code: 0 05:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:49 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')]} 05:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:49 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')]} 05:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:49 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:49 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 05:30:49 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 05:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:49 INFO - 'HOME': '/Users/cltbld', 05:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:49 INFO - 'LOGNAME': 'cltbld', 05:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:49 INFO - 'MOZ_NO_REMOTE': '1', 05:30:49 INFO - 'NO_EM_RESTART': '1', 05:30:49 INFO - 'PAGER': '/bin/cat', 05:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:49 INFO - 'PWD': '/builds/slave/test', 05:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:49 INFO - 'SHELL': '/bin/bash', 05:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:49 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:49 INFO - 'USER': 'cltbld', 05:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:49 INFO - 'XPC_FLAGS': '0x0', 05:30:49 INFO - 'XPC_SERVICE_NAME': '0', 05:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:49 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-bYSITe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:49 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)) 05:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:49 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-aJCyQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:49 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)) 05:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:49 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-0OLQ4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-XFIvt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-jX6HGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-owBrEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-8by31p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-oWZsIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-K0zdlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-4F_B0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-foxO7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-85tcrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:50 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)) 05:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:50 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-i4hhYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:51 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)) 05:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:51 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ZL2bfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:51 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)) 05:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:51 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-rejijm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:51 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)) 05:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:51 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-EY7M5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:51 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)) 05:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:51 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-o6Kgv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:51 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)) 05:30:51 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)) 05:30:51 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)) 05:30:51 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)) 05:30:51 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:30:51 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:30:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:51 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 05:30:51 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 05:30:51 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 05:30:51 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 05:30:52 INFO - Downloading blessings-1.6.tar.gz 05:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:53 INFO - Installing collected packages: blessings 05:30:53 INFO - Running setup.py install for blessings 05:30:53 INFO - Successfully installed blessings 05:30:53 INFO - Cleaning up... 05:30:53 INFO - Return code: 0 05:30:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:53 INFO - Reading from file tmpfile_stdout 05:30:53 INFO - Current package versions: 05:30:53 INFO - blessings == 1.6 05:30:53 INFO - blobuploader == 1.2.4 05:30:53 INFO - docopt == 0.6.1 05:30:53 INFO - functools32 == 3.2.3-2 05:30:53 INFO - jsonschema == 2.5.1 05:30:53 INFO - manifestparser == 1.1 05:30:53 INFO - mozInstall == 1.13 05:30:53 INFO - mozcrash == 1.0 05:30:53 INFO - mozdebug == 0.1 05:30:53 INFO - mozdevice == 0.51 05:30:53 INFO - mozfile == 1.2 05:30:53 INFO - mozhttpd == 0.7 05:30:53 INFO - mozinfo == 0.10 05:30:53 INFO - mozleak == 0.1 05:30:53 INFO - mozlog == 3.4 05:30:53 INFO - moznetwork == 0.27 05:30:53 INFO - mozprocess == 0.25 05:30:53 INFO - mozprofile == 0.28 05:30:53 INFO - mozrunner == 6.13 05:30:53 INFO - mozscreenshot == 0.1 05:30:53 INFO - mozsystemmonitor == 0.3 05:30:53 INFO - moztest == 0.8 05:30:53 INFO - mozversion == 1.4 05:30:53 INFO - psutil == 3.1.1 05:30:53 INFO - requests == 1.2.3 05:30:53 INFO - wsgiref == 0.1.2 05:30:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:53 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')]} 05:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:53 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')]} 05:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:53 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:53 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 05:30:53 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 05:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:53 INFO - 'HOME': '/Users/cltbld', 05:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:53 INFO - 'LOGNAME': 'cltbld', 05:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:53 INFO - 'MOZ_NO_REMOTE': '1', 05:30:53 INFO - 'NO_EM_RESTART': '1', 05:30:53 INFO - 'PAGER': '/bin/cat', 05:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:53 INFO - 'PWD': '/builds/slave/test', 05:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:53 INFO - 'SHELL': '/bin/bash', 05:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:53 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:53 INFO - 'USER': 'cltbld', 05:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:53 INFO - 'XPC_FLAGS': '0x0', 05:30:53 INFO - 'XPC_SERVICE_NAME': '0', 05:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 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)) 05:30:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:30: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 05:30: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 05:30: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 05:30: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 05:30:55 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:30:55 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)) 05:30:55 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)) 05:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:30:55 INFO - Installing collected packages: marionette-driver 05:30:55 INFO - Running setup.py install for marionette-driver 05:30:55 INFO - Successfully installed marionette-driver 05:30:55 INFO - Cleaning up... 05:30:55 INFO - Return code: 0 05:30:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30: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')]} 05:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:55 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 0x102fedc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facd94b7d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, '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.LTcQZCqhmt/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.74gmgnLhhf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 05:30:55 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 05:30:55 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 05:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:55 INFO - 'HOME': '/Users/cltbld', 05:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:55 INFO - 'LOGNAME': 'cltbld', 05:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:55 INFO - 'MOZ_NO_REMOTE': '1', 05:30:55 INFO - 'NO_EM_RESTART': '1', 05:30:55 INFO - 'PAGER': '/bin/cat', 05:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:55 INFO - 'PWD': '/builds/slave/test', 05:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:55 INFO - 'SHELL': '/bin/bash', 05:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:30:55 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:55 INFO - 'USER': 'cltbld', 05:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:55 INFO - 'XPC_FLAGS': '0x0', 05:30:55 INFO - 'XPC_SERVICE_NAME': '0', 05:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:30:55 INFO - Cleaning up... 05:30:55 INFO - Return code: 0 05:30:55 INFO - Running post-action listener: _resource_record_post_action 05:30:55 INFO - Running post-action listener: _start_resource_monitoring 05:30:55 INFO - Starting resource monitoring. 05:30:55 INFO - [mozharness: 2017-06-20 12:30:55.800506Z] Finished create-virtualenv step (success) 05:30:55 INFO - [mozharness: 2017-06-20 12:30:55.801465Z] Running install step. 05:30:55 INFO - Running pre-action listener: _resource_record_pre_action 05:30:55 INFO - Running main action method: install 05:30:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:56 INFO - Reading from file tmpfile_stdout 05:30:56 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:56 INFO - Reading from file tmpfile_stdout 05:30:56 INFO - Output received: 05:30:56 INFO - Usage: mozinstall [options] installer 05:30:56 INFO - Options: 05:30:56 INFO - -h, --help show this help message and exit 05:30:56 INFO - -d DEST, --destination=DEST 05:30:56 INFO - Directory to install application into. [default: 05:30:56 INFO - "/builds/slave/test"] 05:30:56 INFO - --app=APP Application being installed. [default: firefox] 05:30:56 INFO - mkdir: /builds/slave/test/build/application 05:30:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:31:17 INFO - Reading from file tmpfile_stdout 05:31:17 INFO - Output received: 05:31:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:31:17 INFO - Running post-action listener: _resource_record_post_action 05:31:17 INFO - [mozharness: 2017-06-20 12:31:17.529510Z] Finished install step (success) 05:31:17 INFO - [mozharness: 2017-06-20 12:31:17.529676Z] Running setup-mitmproxy step. 05:31:17 INFO - Running pre-action listener: _resource_record_pre_action 05:31:17 INFO - Running main action method: setup_mitmproxy 05:31:17 INFO - Skipping: mitmproxy is not required 05:31:17 INFO - Running post-action listener: _resource_record_post_action 05:31:17 INFO - [mozharness: 2017-06-20 12:31:17.530263Z] Finished setup-mitmproxy step (success) 05:31:17 INFO - [mozharness: 2017-06-20 12:31:17.530362Z] Running run-tests step. 05:31:17 INFO - Running pre-action listener: _resource_record_pre_action 05:31:17 INFO - Running main action method: run_tests 05:31:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:17 INFO - 2017-06-20 05:31:17.545 screenresolution[1626:7402] starting screenresolution argv=screenresolution get 05:31:17 INFO - 2017-06-20 05:31:17.547 screenresolution[1626:7402] Display 0: 1600x1200x32@60 05:31:17 INFO - 2017-06-20 05:31:17.553 screenresolution[1627:7404] starting screenresolution argv=screenresolution list 05:31:17 INFO - Available Modes on Display 0 05:31:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:17 INFO - Intel Iris: 05:31:17 INFO - Chipset Model: Intel Iris 05:31:17 INFO - Type: GPU 05:31:17 INFO - Bus: Built-In 05:31:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:17 INFO - Vendor: Intel (0x8086) 05:31:17 INFO - Device ID: 0x0a2e 05:31:17 INFO - Revision ID: 0x0009 05:31:17 INFO - Displays: 05:31:17 INFO - Display: 05:31:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:17 INFO - Display Serial Number: 1600x1200 60 05:31:17 INFO - Main Display: Yes 05:31:17 INFO - Mirror: Off 05:31:17 INFO - Online: Yes 05:31:17 INFO - Rotation: Supported 05:31:17 INFO - Return code: 0 05:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:31:17 INFO - Python 2.7.3 05:31:17 INFO - Return code: 0 05:31:17 INFO - grabbing minidump binary from tooltool 05:31:17 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')]} 05:31:17 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 0x102d43d30>, '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 0x102d0dcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d34be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:31:17 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 05:31:17 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 05:31:17 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 05:31:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:31:17 INFO - Return code: 0 05:31:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:31:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:31:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:31:17 INFO - ENV: RUST_BACKTRACE is now 1 05:31:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:31:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 05:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --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 05:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LTcQZCqhmt/Render', 05:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:17 INFO - 'HOME': '/Users/cltbld', 05:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:17 INFO - 'LOGNAME': 'cltbld', 05:31:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:31:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:17 INFO - 'MOZ_NO_REMOTE': '1', 05:31:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:31:17 INFO - 'NO_EM_RESTART': '1', 05:31:17 INFO - 'PAGER': '/bin/cat', 05:31:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:17 INFO - 'PWD': '/builds/slave/test', 05:31:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:17 INFO - 'RUST_BACKTRACE': '1', 05:31:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:31:17 INFO - 'SHELL': '/bin/bash', 05:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.74gmgnLhhf/Listeners', 05:31:17 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 05:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:17 INFO - 'USER': 'cltbld', 05:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:17 INFO - 'XPC_FLAGS': '0x0', 05:31:17 INFO - 'XPC_SERVICE_NAME': '0', 05:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 05:31:18 INFO - mozversion application_buildid: 20170620032056 05:31:18 INFO - mozversion application_changeset: d4304ddb6413bd690f757c1eba447c8136887bf9 05:31:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:31:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:31:18 INFO - mozversion application_name: Firefox 05:31:18 INFO - mozversion application_remotingname: firefox-dev 05:31:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:31:18 INFO - mozversion application_vendor: Mozilla 05:31:18 INFO - mozversion application_version: 55.0 05:31:18 INFO - mozversion platform_buildid: 20170620032056 05:31:18 INFO - mozversion platform_changeset: d4304ddb6413bd690f757c1eba447c8136887bf9 05:31:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:31:18 INFO - mozversion platform_version: 55.0 05:31:18 INFO - using testdate: 1497961878 05:31:18 INFO - actual date: 1497961878 05:31:18 INFO - starting webserver on 'localhost:49226' 05:31:18 INFO - SUITE-START | Running 2 tests 05:31:18 INFO - TEST-START | dromaeo_css 05:31:18 INFO - operating with platform_type : mac_ 05:31:18 INFO - Initialising browser for dromaeo_css test... 05:31:18 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpeCsG0p/profile 05:31:18 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 05:31:51 INFO - TEST-INFO | 1637: killed by SIGTERM 05:31:51 INFO - Browser initialized. 05:31:51 INFO - Running cycle 1/1 for dromaeo_css test... 05:31:51 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpeCsG0p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:53 INFO - PID 1647 | 2017-06-20 05:31:53.853 plugin-container[1648:7966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 05:31:53 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:53 INFO - PID 1647 | 2017-06-20 05:31:53.854 plugin-container[1648:7966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:31:53 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:53 INFO - PID 1647 | 2017-06-20 05:31:53.854 plugin-container[1648:7966] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:31:54 INFO - PID 1647 | 2017-06-20 05:31:54.593 plugin-container[1649:8013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 05:31:54 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:54 INFO - PID 1647 | 2017-06-20 05:31:54.594 plugin-container[1649:8013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 05:31:54 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:54 INFO - PID 1647 | 2017-06-20 05:31:54.594 plugin-container[1649:8013] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:32:03 INFO - PID 1647 | RSS: Main: 214933504 05:32:03 INFO - PID 1647 | 05:35:08 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 05:35:10 INFO - PID 1647 | RSS: Main: 233521152 05:35:10 INFO - PID 1647 | 05:38:12 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 05:38:13 INFO - PID 1647 | RSS: Main: 239263744 05:38:13 INFO - PID 1647 | 05:41:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 05:41:15 INFO - PID 1647 | RSS: Main: 243580928 05:41:15 INFO - PID 1647 | 05:44:17 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 05:44:17 INFO - PID 1647 | RSS: Main: 245637120 05:44:17 INFO - PID 1647 | 05:47:20 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 05:47:21 INFO - PID 1647 | RSS: Main: 246185984 05:47:21 INFO - PID 1647 | 05:50:22 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 05:50:23 INFO - PID 1647 | RSS: Main: 244137984 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | __start_tp_report 05:50:23 INFO - PID 1647 | _x_x_mozilla_page_load 05:50:23 INFO - PID 1647 | _x_x_mozilla_page_load_details 05:50:23 INFO - PID 1647 | |i|pagename|runs| 05:50:23 INFO - PID 1647 | |0;dojo.html;2923.4153169366127;3443.9311213775727;3561.5013898054262;3620.9275814483713;3662.10278481772;4722.866512037112;6181.134641150243;6275.152854364659;6307.810765677032;6342.302346741866;6338.207724034496;6367.7771278005275;6378.521610879179;6384.138141350916;6396.648184349859;5515.917261241085;5563.916541251884;5577.442255774431;5590.413006634306;5622.775088996455;5401.351837779472;5466.590005749565;5561.666300021991;5578.63738856975;5580.135079062731;5448.727563621843;5462.126059830408;5494.203340515643;5501.724913754297;5561.805336813192;5550.139728342114;5551.861203469945;5554.0835762099605;5558.027345214612;5599.440055994409;5414.187871819219;5423.918641220384;5453.100238460649;5496.148097044925;5498.9826882026555;6319.052142178664;6325.873482530324;6336.6831658416895;6346.143270658448;6359.491240700733;5069.909969356606;6552.148220731322;6734.191896972346;6840.657967101625;6883.7590684325805;5369.436209198017;6985.266547012541;7003.299670033007;7032.648367581601;7113.608751518665;4944.901101978012;6511.218653761579;6519.380658837403;6539.803805885831;6588.901166482507;6903.309669033057;7023.719051237893;7026.894596581055;7028.648567571601;7038.648067596702;5133.922991155061;6638.767643132467;6641.800745977628;6646.567973081735;6667.166604174478;6936.028955946172;6966.616836074065;6981.511294209458;6984.650767461607;6990.790276291719;5613.915791263134;6861.348171923659;6867.313268673192;6874.828129296808;6875.656217189121;6590.505712071614;6653.866922661617;6677.09859169006;6890.724371025103;6896.103506544117;5114.846554603368;5407.972960135174;5452.727363631802;5496.203050557647;5500.862478437991;3631.2011357501306;4827.131116399082;4900.730459824744;4924.113659541317;4934.506549345108;6292.339304373067;6416.358364163546;6441.1304473895425;6454.4513716333695;6462.935370646327;6983.057287266154;7004.984277279766;7005.054317667152;7008.439324853999;7026.297370263035;4949.208126699711;6157.168782214344;6394.26465956421;6434.935650643527;6437.517186211053;5850.297964244232;5949.196858424144;5959.553033522503;5982.222311099529;5998.220231369894;5737.627054241461;6956.373926346552;6961.6519174042105;6993.580385176906;7101.431885449048;5821.825345239649;6969.686364113729;6997.510174287954;7018.33325834045;7109.431245500451;7162.749303774552;7166.641667916688;7168.605726685082;7175.31834475724;7181.138263408425;6872.484563657646;6883.449324054164;6892.655367231719;6913.481488888454;6944.895826562707;6801.659917004031;6875.7937261882225;7021.403180729695;7067.6112813795735;7096.787096387116;5549.361823390362;6577.769778057745;6705.262421133769;6783.118194634666;6797.592144471348;6038.154658347747;6512.772052978123;6724.529282950147;6768.289329620319;6769.526133170631;6382.6170429774365;6448.838779030561;6471.870562588628;6548.541602087808;6665.366790154928;6569.605823650405;6623.635700036544;6636.43590294811;6683.365080317362;6701.363370479991;6481.805545833632;6581.308962559054;6594.934050659432;6624.973129164987;6651.43462805667 05:50:23 INFO - PID 1647 | |1;ext.html;836.3936146293937;850.7235148576713;1193.22440413731;1232.5686009896538;1247.2017908538537;37541;38390.232195356075;38416.80791596045;38433;38495.23009539811;31086;38551.03622409441;38570.80714596423;38623;38677.226455470874;7748.806279842993;7823.843523129534;7886.408519361043;7888.171741967097;7890.250426209501;8244.628991695388;8462.915370846275;8488.87266690997;8506.447080939719;8507.31941444686;8484.40609157356;8515.403921725481;8570.400071994964;8582.442141260799;8594.699185528507;8490.575471226444;8501.872471912926;8557.229849313591;8559.101294364096;8560.400771945966;8300.626471808782;8373.037100733432;8384.790380240482;8384.916150838475;8393.916060839374;36419.81790091059;36620;36788.712395066046;36995.445068324;37098.443523347174;3523.6828685418436;3535.292941411728;3545.6454354564594;3546.538949936492;3547.450145227494;30080;30112.397752044835;30135;30157.849210754026;30194.84902575473;3039.9816061619385;3063.2954420483193;3099.977007587491;3141.4973604223214;3146.8426578670974;331491;332134.0179897328;332182;333325;334146;38436.8078159613;39125;39192.2161556773;47305.76347118242;47790.76104619524;50386.74806626017;50427.24359134616;50523.747381263594;50650.74674626677;50673;15220;15255.160966146968;15392.384304627689;15410;15427.84572154264;12295;12593.30736809466;12614.684632884251;12783.61649150527;12831.35843207836;13498.93250533741;13523.85047270993;13525.45898164082;13528.86471135276;13539.255340956144;4517.3901523293935;4979.053979743837;5066.721330326794;6006.309274433496;6107.633541987406;797.720797720793;919.8823429533015;1011.0900189829213;1014.660088870222;1018.9745256368502;19771.319437847655;19823.207071717257;20422.774633521738;22357.664635030487;23222.3033309001;878.3061381508664;1063.160103518212;1068.2682362581502;1080.3355936099242;1082.658962426833;9139.90860091404;9930.80138397228;11641.708957276136;12330.445129969174;12358.814617780741;341539;344506;348186;348240;348927;51091.722706932625;51636.96726065458;51700;51768.48231517712;52017.47982520202;1230.194222784078;1480.8148981377328;1486.197453375187;1488.2335597167466;1497.9700405992037;18262;22710.432239194153;22821.429464263525;23764.762352376947;23951.28146155618;37766.43350349692;37869.81065094767;38059.619403805606;38237;38246.80876595599;309379;309669;309768.45115774276;309771;310696.446517766;27307.863460683366;27742.02902898389;27887.581686274316;32768.67231327656;33181.50227746538;34708;34753.82623086868;39103.02242443848;39427.80286098666;39692.603073968894;26618;27614.861925690242;27717;29649.25876853096;30001;29278.414431710822;29417;29419;29447.116586502438;29715.257118572208;32546.83726581352;33074.83462582672;33344.49983250205;34805.65194348024;34847.303053938274;25425.872870636267;28374;29508.557371639872;29588.704112959455;29897.70102299036;19507.12217950167;23525.294241172793;23594.646080308466;23612.645810312515;23696.52606947886 05:50:23 INFO - PID 1647 | |2;jquery.html;514.0618371472062;517.2035065998363;521.4238266715282;529.8489930369844;544.5235419008368;18035.36876209333;22821.771782282194;22870.428239294026;22903.74029428381;22930.541389172206;22601.886990565024;25000.374990625245;25147.245582632506;25172.37069073274;25196.74803251967;6277.560570760037;6440.516961227903;6445.709943052574;6447.258565264984;6458.741650333981;6087.756489740405;6393.872122557546;6470.352964703516;6470.9029364559565;6474.838129046764;5173.275741396207;5806.970965145189;6363.0773537836985;6427.614343139403;6432.163818703562;6410.711517981678;6416;6453.9677301613665;6458.773942911999;6464.967675161641;5894.734736936848;5929.644221346733;5939.851503712442;5942.494887934531;6011.549133814981;25517.489650207077;29505.508788066592;29837.850810746026;30275.545866812015;30323;2163.935081947544;2239.6304609739373;2263.0495192019225;2264.4225722440674;2268.534950335177;18681.906590467097;19245.61508769831;19439.51401214967;19526.804731952783;19537.706934395996;1910.512819231101;1975.140813746025;2194.484296190394;2223.722034745657;2241.4508445430974;200470;202872;203019.9698003016;205460;205853;14532.055416398112;15482.148481833383;15635.765463518057;15722.213889305489;16224.702023838301;19108.331208407915;20458.38624841257;20752.377428677162;20773.89613051925;20778.48053798667;8593.312534997185;10202.846957295647;10274.589016439259;10372.01465860742;10426.322289051188;9646.662366817127;9682.177014953668;9752.804943901225;9803.362781419184;9813.969533199053;8448.901642786399;9172.8624070639;9290.581923813445;9345.813083738423;9359.672411465566;2602.0892687559203;2619.9214023579325;2635.354338187118;2679.370347968226;2714.2942834862683;931.4597533430594;933.7945651956559;938.1931538876561;954.203240294362;976.4571976277606;21799.019044143053;21805.345839624835;21844.78155218459;22272.88863555672;22475.775242247368;978.1148061004802;995.3430735714394;1007.3378924774181;1025.733309339577;1034.7361422837128;9248.150369926041;11097.778044439066;11141.610043648434;11380.374079425546;11543.595974140864;581812.1381404311;590113;593922;604979;615538;154934;163087;164420;165256;167337.3266267346;1131.801934661431;1166.7549814538872;1177.4054102678092;1182.5742732616252;1183.2427246562206;19464.221431143163;19764.50588735293;20406.26546743484;20468.38594842157;20676.862772547054;68702;68975.65512172406;71877.64061179869;72178.63910680624;74108.62945685453;95207.52396238252;96059;98991.53529626428;99967.50016250163;100309;12146.028317734383;12157.878421215675;12261.754764904474;12316.753664926831;12444.688882778002;15681.764773528634;15737.44918927832;18439.170237339084;18760.155792989623;19005.904970475058;14820.184889831164;15112.319945602254;15114.319855606744;15184.696306074038;15240.618984525476;13422.463101475832;13590.59228223155;13605;13662.385192666155;14163;14949.476768313058;15666.295016724047;15707.293171807067;15826.683466330378;15910.204489775697;9979.251556133175;12032.217905836093;12039.217450865666;12095.455704493143;12187.390630468264;15047.623809404853;15208.631223189523;15287.312070956612;15296.852736045064;15298.31157597933 05:50:23 INFO - PID 1647 | |3;mootools.html;1400.9019368644197;1449.9632762574756;1459.02245495518;1459.263072148564;1461.7076584683066;13415.993800464968;14026.228557429336;14064.71870562588;14066.367013484392;14083.155010699353;11624.941875290611;13629.386677599507;13896.374663140157;14045.719085618282;14059.648508787286;2415.5168966206743;2806.130099669099;2837.6878543360317;2862.6707928588166;2870.712928707123;2457.324235835151;2716.2801857507775;2786;2813.6623605167306;2834.3055951291963;2794.552871540554;2848.643919510061;2856.600075989363;2862.0984389917107;2867.541193409055;2506.6240063990367;2665.2537289558954;2794.874230659615;2824.816386934863;2829.4906916754976;2772.639556857616;2781.29077085344;2796.66440027196;2837.404145129525;2859.599656048155;13508.392122354519;13619.931900340534;13624.659383515393;13646.931765341209;13670.726585468334;7495.550266983998;7783.260590243918;7791.181925897812;7800.297973182441;7824.726134585262;4523.547645235483;4635.351050852883;4815.277708343741;4854.417469903634;4906.092372910987;1404.5294826233223;1412.9222892740895;1415.3701602786764;1436.956891293263;1438.5180964376946;18910.338138165236;19478.999999999858;20677.172913083457;20759.481012974644;20794.48013799652;6044.667543285162;6670.399664030164;6982.441404687613;7027.508074434741;7030.578165310098;14489.275536223147;14627.049241799248;14710;14713.485028023968;14720.558383248519;7404.259574042552;7602.50583712057;7622.428317876071;7627.656755446018;7658.195889431529;6924.792256232321;7116.46626503004;7139.92860071403;7140.642967851587;7164.785056448315;5259.815906443333;5323.908598737249;5340.732963351817;5393.379761327419;5413.079776438015;1488.6501672107047;1493.589262952675;1497.4309762290477;1508.4569554960426;1512.2741084279387;814.6252723747143;816.8360086876178;833.6540335760592;846.8645016797283;870.4081224767092;7048.753293634698;7191.388731957738;7396.3343299103335;7411.444141689395;7465.440091993122;652.8379484517567;677.7255211639196;684.1345697692423;704.5737328916034;717.189575779366;4556.660631464048;4752;4839.467658557556;5140.640155189176;5354.785808567692;18300.633987320445;18539.165737541844;18702;18906.52733681669;18948.526286842938;17928.551786205448;18286.17712202954;18335.89984600928;18576.25694972186;18777.530561736065;7690.346320562703;9268.934072581604;9628.18160456355;9704.854427183598;9757.926628070907;6756.898646520208;7312.696500602293;7532.7740167795055;7782.727604533814;7956.681732730857;16079.437219697254;16231.918840405722;16321.673566528365;16565.503034908972;16640.50078497647;16742.995420274823;17226.483205503853;17311.826881731526;17466.388676396266;17748.645027099643;10445.791084178425;10985.231033827875;11174.161937854531;11233.831492527783;11245.831312530156;12683.87316126827;12777.23336599807;12797;12817.679558010752;12821.294828784507;10175.28772985884;10210.642627508001;10242.283040187413;10247.077763001365;10446.320989135682;8654.653813847628;9991.700248992542;10263;10308.072273495422;10369.014943580349;9214.44713317203;9292.117248861347;9312.25501959828;9401.388909720847;9438.339316247797;6361.7137228823885;6499.480041596756;6810.080639113657;7061.293870612898;7234.602096884511;6700.93299067003;6883.827904302432;6934.479914006369;7154.35610795031;7159.391451726661 05:50:23 INFO - PID 1647 | |4;prototype.html;430.1440134133076;449.25199542761305;458.9265717485202;467.84327250371126;475.91909375406175;6308.485963368793;6569.50728695348;6654.567453115556;6675.399214070732;6690.531662783601;5430.8913821723545;6459.700538391611;6475.90286145707;6485.2217733871885;6499.35006499351;4004.419359192915;4838.491958344384;4840.685355451885;4868.56182943535;4870.805167793302;4459.598636122749;4500.9197792529885;4536.614387777043;4811.13399588074;4825.271234190495;4315.43899293093;4698.483166851644;4776.617870570363;4777.90444191116;4803.447603525605;4430.357598148251;4441.866743997685;4495.033567782943;4786.138495070886;4802.543758342969;3681.9447708284397;4180.979095104535;4192.24539582875;4194.8112334945;4201.789910504463;6271.686415679198;6277.152584401093;6282.842928926768;6287.622742635411;6336.58812177207;5717.857053573653;5724.8282551523525;5726.083826587767;5728.513076388512;5729.656220626775;5620.9437905620825;5724;6917.446604271646;6973;6983.2318444971015;4527;4794.808207671689;4829.855104346875;4833.29917162013;4846.854594362175;6406.583572067801;7352.63236838156;7418.517796343274;7420.888686669704;7425;4157.792110394468;4322.8976610964;4927.384076990376;4943.950560494421;4971.726555039508;3966.722329436912;4425.291953287458;4851.151048566487;5012.924806127911;5016.072026675077;4954.950450495521;5746.166805813134;5754.568407369464;5756.33802112754;5774.595778295479;7016.36852683261;7023.473239507058;7026.262242464468;7040.204747789281;7125.42996560274;1528.2893454543691;1545.4977132432005;1655.5861034741315;1655.9503214903573;1696.703076961527;1052.7682611344744;1068.673601642037;1144.793937091315;1159.7912375772441;1165.953361865516;5122.924185920924;5805.477507024304;6225.719842607094;6566.244881838554;6569.244536878225;7188.712451501881;7280.563166210044;7284.629222466584;7292.671829767673;7299.5255308404785;6490.318516555788;6499.350064993463;6524.314946930599;6543.869122617616;6547.705353259114;3160.668129846333;3718.8512459501317;3768.1333293342136;3771.0383852117534;3776.0930729981183;6241.877761561091;7447.590382528904;7468.5145465544565;7474.701011959461;7479.364254038359;7417.592032438268;7437.479376443705;7458;7470.252974702486;7488.925110748932;5891.262077687093;7306.452016098708;7348.448866335153;7392.889106663404;7455.403567714569;7192.676329565077;7196.784096477114;7217.530860494051;7218.386437152901;7246.96376518114;6682.887590992991;7100.964495177491;7127.750528731677;7152.284771522806;7167.964160179165;6943.861122777617;7029.3673569379;7458.813529661802;7459.701611935679;7537.54774713519;4401.383806267061;4652.790624421841;4893.724418145437;4915.674206698082;4918.827841025547;7312.378447831994;7319.7922342812835;7320.231375705687;7324.267573242633;7352.558846469229;6222.719977601109;6563.3764792344655;6985.965070174617;7005.579665220103;7014;6305.495560355253;6924.619145947021;6936;6943.236244013054;6976.895346569704;6448.838779030561;6768.255491895986;6779.389854913082;6789.660516974231;6801.455883529405;5868.823935281948;6342.49260059185;6916.100906882073;6944.409725173215;7003.6848341823725;5910.822675319747;6372.661741034434;6801.489888258499;6802.829929251809;6825.897611535732 05:50:23 INFO - PID 1647 | |5;yui.html;761.5544906229853;805.9073206581238;807.4105902691034;816.9673213071477;820.7291593774055;16679.83320166798;17163.91418042911;17253.051082190475;17255.74116388255;17382.47852564422;17505.91247043763;18793.342233021845;18835.964021978787;18948.810511894877;18949.905250473763;985.3989066669326;987.723437437515;1002.5338217728755;1010.914072303855;1014.6702321745425;991.6529214774844;997.7405874472616;999.7500624843789;1000.4497526360509;1001.144021861309;927.9860802087974;970.6699722094487;1057.8413238014282;1061.3419679798535;1064.914806815453;971.68905950096;992.9375568142091;1040.8282633365486;1042.801867645145;1045.7594753206768;757.121738783009;757.3486801350834;757.4054367321626;757.9393648508161;760.3612965109335;17134;17405.651886962318;17423.431891129858;17452.825471745247;17622.471325860246;17273.56816079608;17601.471955841218;17666.293348266056;17718.379856704953;17905.641887162314;14323.35544900482;14378.496752613713;14446.783298250535;14494.34775435108;14519.709605807824;3543.025667941337;3571.4107172316535;3583.0325812030605;3597.928041439183;3601.243738814852;114028.429857851;125559;127521;135084.999999999;138789;13312.800307995194;14231.43074277038;14240.501582444565;14387.92806035963;14514.637134071521;26906.73093269042;26910.192694219204;27124.59313110305;27366.179014629593;27388.58917116245;8040.15578364275;8154.817551455125;8201.30288925448;8229.753107406787;8247.587620618946;4030.435738996543;4062;4088.9591104089172;4105.199486100182;4110.815013324408;4686.343911852344;4753.881152971134;4763.857084287477;4774.188387974053;4774.427068751772;1006.4816619440994;1042.4943902207424;1047.2930771729052;1050.3015494695942;1051.2536099369381;587.867729760809;599.1462166412931;599.4694695194784;629.6631302253272;655.9180102487189;9468.763280918032;11068.889311106946;12040.157188996862;12052;12268.754624907451;577.5783677915146;579.7333226715674;593.1636392686291;613.9754409823557;614.1310046284547;5086.593072554187;5323.920141197885;5338.119210330329;5546.889062218733;5619.157126430986;141684;151880;155254;159250;162028;112939.87060129458;114805.42597287128;123141;125615.74384256224;131141;103590;107731.38402923962;109565.45217273863;110290;111467;9213.216876565568;9423;9972.551235194578;10520;11093.557837481076;47349.289760654305;48147.51852481571;49432.50567494279;49615.75192124016;50267;67450;68532.97200542096;69814.30185698078;71566.64216678884;71952;13979.440822367398;14985.925070374578;15145.848541514444;15172.772408413663;16588.170591470138;18809.717854231923;18960.146793394608;18992.335268265546;19125.043747812277;19319.8068019318;16924.830751692327;17160.313587456363;17910;18060.819391805915;18261.726074109163;16822.915885420494;17402.73895891588;17404.303827846743;17586;17841;16516.339346426008;16890.74663880018;17076;17272.395466158625;17378.73931891048;9579.307926228728;11081.445927703744;11342.829857551978;11979.460924258603;12422.751544968869;13561;14213.64465888361;14302.356393962089;14379.92810035985;14675 05:50:23 INFO - PID 1647 | __end_tp_report 05:50:23 INFO - PID 1647 | __start_cc_report 05:50:23 INFO - PID 1647 | _x_x_mozilla_cycle_collect,402 05:50:23 INFO - PID 1647 | __end_cc_report 05:50:23 INFO - PID 1647 | __startTimestamp1497963023061__endTimestamp 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | ------- Summary: start ------- 05:50:23 INFO - PID 1647 | Number of tests: 6 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6229.72 Median:6481.81 stddev:838.43 (12.9%) stddev-sans-first:799.88 05:50:23 INFO - PID 1647 | Values: 2923.4 3443.9 3561.5 3620.9 3662.1 4722.9 6181.1 6275.2 6307.8 6342.3 6338.2 6367.8 6378.5 6384.1 6396.6 5515.9 5563.9 5577.4 5590.4 5622.8 5401.4 5466.6 5561.7 5578.6 5580.1 5448.7 5462.1 5494.2 5501.7 5561.8 5550.1 5551.9 5554.1 5558.0 5599.4 5414.2 5423.9 5453.1 5496.1 5499.0 6319.1 6325.9 6336.7 6346.1 6359.5 5069.9 6552.1 6734.2 6840.7 6883.8 5369.4 6985.3 7003.3 7032.6 7113.6 4944.9 6511.2 6519.4 6539.8 6588.9 6903.3 7023.7 7026.9 7028.6 7038.6 5133.9 6638.8 6641.8 6646.6 6667.2 6936.0 6966.6 6981.5 6984.7 6990.8 5613.9 6861.3 6867.3 6874.8 6875.7 6590.5 6653.9 6677.1 6890.7 6896.1 5114.8 5408.0 5452.7 5496.2 5500.9 3631.2 4827.1 4900.7 4924.1 4934.5 6292.3 6416.4 6441.1 6454.5 6462.9 6983.1 7005.0 7005.1 7008.4 7026.3 4949.2 6157.2 6394.3 6434.9 6437.5 5850.3 5949.2 5959.6 5982.2 5998.2 5737.6 6956.4 6961.7 6993.6 7101.4 5821.8 6969.7 6997.5 7018.3 7109.4 7162.7 7166.6 7168.6 7175.3 7181.1 6872.5 6883.4 6892.7 6913.5 6944.9 6801.7 6875.8 7021.4 7067.6 7096.8 5549.4 6577.8 6705.3 6783.1 6797.6 6038.2 6512.8 6724.5 6768.3 6769.5 6382.6 6448.8 6471.9 6548.5 6665.4 6569.6 6623.6 6636.4 6683.4 6701.4 6481.8 6581.3 6594.9 6625.0 6651.4 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:46611.38 Median:183745.25 stddev:86921.42 (47.3%) stddev-sans-first:87097.31 05:50:23 INFO - PID 1647 | Values: 836.4 850.7 1193.2 1232.6 1247.2 37541.0 38390.2 38416.8 38433.0 38495.2 31086.0 38551.0 38570.8 38623.0 38677.2 7748.8 7823.8 7886.4 7888.2 7890.3 8244.6 8462.9 8488.9 8506.4 8507.3 8484.4 8515.4 8570.4 8582.4 8594.7 8490.6 8501.9 8557.2 8559.1 8560.4 8300.6 8373.0 8384.8 8384.9 8393.9 36419.8 36620.0 36788.7 36995.4 37098.4 3523.7 3535.3 3545.6 3546.5 3547.5 30080.0 30112.4 30135.0 30157.8 30194.8 3040.0 3063.3 3100.0 3141.5 3146.8 331491.0 332134.0 332182.0 333325.0 334146.0 38436.8 39125.0 39192.2 47305.8 47790.8 50386.7 50427.2 50523.7 50650.7 50673.0 15220.0 15255.2 15392.4 15410.0 15427.8 12295.0 12593.3 12614.7 12783.6 12831.4 13498.9 13523.9 13525.5 13528.9 13539.3 4517.4 4979.1 5066.7 6006.3 6107.6 797.7 919.9 1011.1 1014.7 1019.0 19771.3 19823.2 20422.8 22357.7 23222.3 878.3 1063.2 1068.3 1080.3 1082.7 9139.9 9930.8 11641.7 12330.4 12358.8 341539.0 344506.0 348186.0 348240.0 348927.0 51091.7 51637.0 51700.0 51768.5 52017.5 1230.2 1480.8 1486.2 1488.2 1498.0 18262.0 22710.4 22821.4 23764.8 23951.3 37766.4 37869.8 38059.6 38237.0 38246.8 309379.0 309669.0 309768.5 309771.0 310696.4 27307.9 27742.0 27887.6 32768.7 33181.5 34708.0 34753.8 39103.0 39427.8 39692.6 26618.0 27614.9 27717.0 29649.3 30001.0 29278.4 29417.0 29419.0 29447.1 29715.3 32546.8 33074.8 33344.5 34805.7 34847.3 25425.9 28374.0 29508.6 29588.7 29897.7 19507.1 23525.3 23594.6 23612.6 23696.5 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:41354.44 Median:12256.26 stddev:103565.97 (845.0%) stddev-sans-first:103811.10 05:50:23 INFO - PID 1647 | Values: 514.1 517.2 521.4 529.8 544.5 18035.4 22821.8 22870.4 22903.7 22930.5 22601.9 25000.4 25147.2 25172.4 25196.7 6277.6 6440.5 6445.7 6447.3 6458.7 6087.8 6393.9 6470.4 6470.9 6474.8 5173.3 5807.0 6363.1 6427.6 6432.2 6410.7 6416.0 6454.0 6458.8 6465.0 5894.7 5929.6 5939.9 5942.5 6011.5 25517.5 29505.5 29837.9 30275.5 30323.0 2163.9 2239.6 2263.0 2264.4 2268.5 18681.9 19245.6 19439.5 19526.8 19537.7 1910.5 1975.1 2194.5 2223.7 2241.5 200470.0 202872.0 203020.0 205460.0 205853.0 14532.1 15482.1 15635.8 15722.2 16224.7 19108.3 20458.4 20752.4 20773.9 20778.5 8593.3 10202.8 10274.6 10372.0 10426.3 9646.7 9682.2 9752.8 9803.4 9814.0 8448.9 9172.9 9290.6 9345.8 9359.7 2602.1 2619.9 2635.4 2679.4 2714.3 931.5 933.8 938.2 954.2 976.5 21799.0 21805.3 21844.8 22272.9 22475.8 978.1 995.3 1007.3 1025.7 1034.7 9248.2 11097.8 11141.6 11380.4 11543.6 581812.1 590113.0 593922.0 604979.0 615538.0 154934.0 163087.0 164420.0 165256.0 167337.3 1131.8 1166.8 1177.4 1182.6 1183.2 19464.2 19764.5 20406.3 20468.4 20676.9 68702.0 68975.7 71877.6 72178.6 74108.6 95207.5 96059.0 98991.5 99967.5 100309.0 12146.0 12157.9 12261.8 12316.8 12444.7 15681.8 15737.4 18439.2 18760.2 19005.9 14820.2 15112.3 15114.3 15184.7 15240.6 13422.5 13590.6 13605.0 13662.4 14163.0 14949.5 15666.3 15707.3 15826.7 15910.2 9979.3 12032.2 12039.2 12095.5 12187.4 15047.6 15208.6 15287.3 15296.9 15298.3 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:8374.85 Median:2819.24 stddev:5571.91 (197.6%) stddev-sans-first:5562.90 05:50:23 INFO - PID 1647 | Values: 1400.9 1450.0 1459.0 1459.3 1461.7 13416.0 14026.2 14064.7 14066.4 14083.2 11624.9 13629.4 13896.4 14045.7 14059.6 2415.5 2806.1 2837.7 2862.7 2870.7 2457.3 2716.3 2786.0 2813.7 2834.3 2794.6 2848.6 2856.6 2862.1 2867.5 2506.6 2665.3 2794.9 2824.8 2829.5 2772.6 2781.3 2796.7 2837.4 2859.6 13508.4 13619.9 13624.7 13646.9 13670.7 7495.6 7783.3 7791.2 7800.3 7824.7 4523.5 4635.4 4815.3 4854.4 4906.1 1404.5 1412.9 1415.4 1437.0 1438.5 18910.3 19479.0 20677.2 20759.5 20794.5 6044.7 6670.4 6982.4 7027.5 7030.6 14489.3 14627.0 14710.0 14713.5 14720.6 7404.3 7602.5 7622.4 7627.7 7658.2 6924.8 7116.5 7139.9 7140.6 7164.8 5259.8 5323.9 5340.7 5393.4 5413.1 1488.7 1493.6 1497.4 1508.5 1512.3 814.6 816.8 833.7 846.9 870.4 7048.8 7191.4 7396.3 7411.4 7465.4 652.8 677.7 684.1 704.6 717.2 4556.7 4752.0 4839.5 5140.6 5354.8 18300.6 18539.2 18702.0 18906.5 18948.5 17928.6 18286.2 18335.9 18576.3 18777.5 7690.3 9268.9 9628.2 9704.9 9757.9 6756.9 7312.7 7532.8 7782.7 7956.7 16079.4 16231.9 16321.7 16565.5 16640.5 16743.0 17226.5 17311.8 17466.4 17748.6 10445.8 10985.2 11174.2 11233.8 11245.8 12683.9 12777.2 12797.0 12817.7 12821.3 10175.3 10210.6 10242.3 10247.1 10446.3 8654.7 9991.7 10263.0 10308.1 10369.0 9214.4 9292.1 9312.3 9401.4 9438.3 6361.7 6499.5 6810.1 7061.3 7234.6 6700.9 6883.8 6934.5 7154.4 7159.4 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5650.71 Median:6306.99 stddev:1785.92 (28.3%) stddev-sans-first:1747.42 05:50:23 INFO - PID 1647 | Values: 430.1 449.3 458.9 467.8 475.9 6308.5 6569.5 6654.6 6675.4 6690.5 5430.9 6459.7 6475.9 6485.2 6499.4 4004.4 4838.5 4840.7 4868.6 4870.8 4459.6 4500.9 4536.6 4811.1 4825.3 4315.4 4698.5 4776.6 4777.9 4803.4 4430.4 4441.9 4495.0 4786.1 4802.5 3681.9 4181.0 4192.2 4194.8 4201.8 6271.7 6277.2 6282.8 6287.6 6336.6 5717.9 5724.8 5726.1 5728.5 5729.7 5620.9 5724.0 6917.4 6973.0 6983.2 4527.0 4794.8 4829.9 4833.3 4846.9 6406.6 7352.6 7418.5 7420.9 7425.0 4157.8 4322.9 4927.4 4944.0 4971.7 3966.7 4425.3 4851.2 5012.9 5016.1 4955.0 5746.2 5754.6 5756.3 5774.6 7016.4 7023.5 7026.3 7040.2 7125.4 1528.3 1545.5 1655.6 1656.0 1696.7 1052.8 1068.7 1144.8 1159.8 1166.0 5122.9 5805.5 6225.7 6566.2 6569.2 7188.7 7280.6 7284.6 7292.7 7299.5 6490.3 6499.4 6524.3 6543.9 6547.7 3160.7 3718.9 3768.1 3771.0 3776.1 6241.9 7447.6 7468.5 7474.7 7479.4 7417.6 7437.5 7458.0 7470.3 7488.9 5891.3 7306.5 7348.4 7392.9 7455.4 7192.7 7196.8 7217.5 7218.4 7247.0 6682.9 7101.0 7127.8 7152.3 7168.0 6943.9 7029.4 7458.8 7459.7 7537.5 4401.4 4652.8 4893.7 4915.7 4918.8 7312.4 7319.8 7320.2 7324.3 7352.6 6222.7 6563.4 6986.0 7005.6 7014.0 6305.5 6924.6 6936.0 6943.2 6976.9 6448.8 6768.3 6779.4 6789.7 6801.5 5868.8 6342.5 6916.1 6944.4 7003.7 5910.8 6372.7 6801.5 6802.8 6825.9 05:50:23 INFO - PID 1647 | 05:50:23 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25685.08 Median:17692.34 stddev:39206.42 (221.6%) stddev-sans-first:39271.74 05:50:23 INFO - PID 1647 | Values: 761.6 805.9 807.4 817.0 820.7 16679.8 17163.9 17253.1 17255.7 17382.5 17505.9 18793.3 18836.0 18948.8 18949.9 985.4 987.7 1002.5 1010.9 1014.7 991.7 997.7 999.8 1000.4 1001.1 928.0 970.7 1057.8 1061.3 1064.9 971.7 992.9 1040.8 1042.8 1045.8 757.1 757.3 757.4 757.9 760.4 17134.0 17405.7 17423.4 17452.8 17622.5 17273.6 17601.5 17666.3 17718.4 17905.6 14323.4 14378.5 14446.8 14494.3 14519.7 3543.0 3571.4 3583.0 3597.9 3601.2 114028.4 125559.0 127521.0 135085.0 138789.0 13312.8 14231.4 14240.5 14387.9 14514.6 26906.7 26910.2 27124.6 27366.2 27388.6 8040.2 8154.8 8201.3 8229.8 8247.6 4030.4 4062.0 4089.0 4105.2 4110.8 4686.3 4753.9 4763.9 4774.2 4774.4 1006.5 1042.5 1047.3 1050.3 1051.3 587.9 599.1 599.5 629.7 655.9 9468.8 11068.9 12040.2 12052.0 12268.8 577.6 579.7 593.2 614.0 614.1 5086.6 5323.9 5338.1 5546.9 5619.2 141684.0 151880.0 155254.0 159250.0 162028.0 112939.9 114805.4 123141.0 125615.7 131141.0 103590.0 107731.4 109565.5 110290.0 111467.0 9213.2 9423.0 9972.6 10520.0 11093.6 47349.3 48147.5 49432.5 49615.8 50267.0 67450.0 68533.0 69814.3 71566.6 71952.0 13979.4 14985.9 15145.8 15172.8 16588.2 18809.7 18960.1 18992.3 19125.0 19319.8 16924.8 17160.3 17910.0 18060.8 18261.7 16822.9 17402.7 17404.3 17586.0 17841.0 16516.3 16890.7 17076.0 17272.4 17378.7 9579.3 11081.4 11342.8 11979.5 12422.8 13561.0 14213.6 14302.4 14379.9 14675.0 05:50:23 INFO - PID 1647 | -------- Summary: end -------- 05:50:23 INFO - PID 1647 | 05:50:23 INFO - TEST-INFO | 1647: exit 0 05:50:23 INFO - TEST-OK | dromaeo_css | took 1145430ms 05:50:23 INFO - TEST-START | kraken 05:50:23 INFO - operating with platform_type : mac_ 05:50:23 INFO - Initialising browser for kraken test... 05:50:23 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpfiYyVk/profile 05:50:23 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 05:50:56 INFO - TEST-INFO | 1658: killed by SIGTERM 05:50:56 INFO - Browser initialized. 05:50:56 INFO - Running cycle 1/1 for kraken test... 05:50:56 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpfiYyVk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:58 INFO - PID 1662 | 2017-06-20 05:50:58.933 plugin-container[1663:10644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 05:50:58 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:58 INFO - PID 1662 | 2017-06-20 05:50:58.934 plugin-container[1663:10644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 05:50:58 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:58 INFO - PID 1662 | 2017-06-20 05:50:58.934 plugin-container[1663:10644] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:50:59 INFO - PID 1662 | 2017-06-20 05:50:59.523 plugin-container[1664:10690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 05:50:59 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:59 INFO - PID 1662 | 2017-06-20 05:50:59.523 plugin-container[1664:10690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9513, name = 'com.apple.CFPasteboardClient' 05:50:59 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:59 INFO - PID 1662 | 2017-06-20 05:50:59.523 plugin-container[1664:10690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:51:08 INFO - PID 1662 | RSS: Main: 214626304 05:51:08 INFO - PID 1662 | 05:51:11 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:12 INFO - PID 1662 | RSS: Main: 228610048 05:51:12 INFO - PID 1662 | 05:51:15 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 05:51:15 INFO - PID 1662 | RSS: Main: 238292992 05:51:15 INFO - PID 1662 | 05:51:19 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 05:51:19 INFO - PID 1662 | RSS: Main: 243073024 05:51:19 INFO - PID 1662 | 05:51:21 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 05:51:22 INFO - PID 1662 | RSS: Main: 244092928 05:51:22 INFO - PID 1662 | 05:51:24 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:24 INFO - PID 1662 | RSS: Main: 245121024 05:51:24 INFO - PID 1662 | 05:51:27 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:28 INFO - PID 1662 | RSS: Main: 250445824 05:51:28 INFO - PID 1662 | 05:51:31 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:32 INFO - PID 1662 | RSS: Main: 250880000 05:51:32 INFO - PID 1662 | 05:51:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 05:51:35 INFO - PID 1662 | RSS: Main: 250953728 05:51:35 INFO - PID 1662 | 05:51:37 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:37 INFO - PID 1662 | RSS: Main: 249647104 05:51:37 INFO - PID 1662 | 05:51:39 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:40 INFO - PID 1662 | RSS: Main: 249647104 05:51:40 INFO - PID 1662 | 05:51:42 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:43 INFO - PID 1662 | RSS: Main: 249581568 05:51:43 INFO - PID 1662 | 05:51:45 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:46 INFO - PID 1662 | RSS: Main: 249528320 05:51:46 INFO - PID 1662 | 05:51:49 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:50 INFO - PID 1662 | RSS: Main: 249790464 05:51:50 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 05:51:52 INFO - PID 1662 | RSS: Main: 252186624 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | __start_tp_report 05:51:52 INFO - PID 1662 | _x_x_mozilla_page_load 05:51:52 INFO - PID 1662 | _x_x_mozilla_page_load_details 05:51:52 INFO - PID 1662 | |i|pagename|runs| 05:51:52 INFO - PID 1662 | |0;ai-astar;103;116;111;164;167;169;114;104;114;126 05:51:52 INFO - PID 1662 | |1;audio-beat-detection;145;115;130;146;112;125;124;110;157;111 05:51:52 INFO - PID 1662 | |2;audio-dft;202;199;196;198;203;230;199;195;196;218 05:51:52 INFO - PID 1662 | |3;audio-fft;70;82;78;159;65;86;85;77;122;61 05:51:52 INFO - PID 1662 | |4;audio-oscillator;69;76;125;71;71;78;107;70;77;129 05:51:52 INFO - PID 1662 | |5;imaging-gaussian-blur;92;98;92;91;93;92;92;93;91;88 05:51:52 INFO - PID 1662 | |6;imaging-darkroom;106;136;152;109;96;96;95;96;106;96 05:51:52 INFO - PID 1662 | |7;imaging-desaturate;92;95;91;92;91;86;85;101;106;144 05:51:52 INFO - PID 1662 | |8;json-parse-financial;49;49;49;52;52;53;52;54;52;53 05:51:52 INFO - PID 1662 | |9;json-stringify-tinderbox;57;56;59;64;59;62;84;83;55;58 05:51:52 INFO - PID 1662 | |10;stanford-crypto-aes;79;77;76;79;77;80;81;81;80;78 05:51:52 INFO - PID 1662 | |11;stanford-crypto-ccm;124;175;141;147;154;138;152;135;144;165 05:51:52 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;177;181;177;177;179;195;216;215;217;173 05:51:52 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;67;65;66;66;63;67;72;57;77;99 05:51:52 INFO - PID 1662 | __end_tp_report 05:51:52 INFO - PID 1662 | __start_cc_report 05:51:52 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1019 05:51:52 INFO - PID 1662 | __end_cc_report 05:51:52 INFO - PID 1662 | __startTimestamp1497963112372__endTimestamp 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | ------- Summary: start ------- 05:51:52 INFO - PID 1662 | Number of tests: 14 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:128.80 Median:121.00 stddev:26.92 (22.2%) stddev-sans-first:26.88 05:51:52 INFO - PID 1662 | Values: 103.0 116.0 111.0 164.0 167.0 169.0 114.0 104.0 114.0 126.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:127.50 stddev:16.73 (13.1%) stddev-sans-first:16.50 05:51:52 INFO - PID 1662 | Values: 145.0 115.0 130.0 146.0 112.0 125.0 124.0 110.0 157.0 111.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:203.60 Median:200.50 stddev:11.40 (5.7%) stddev-sans-first:12.08 05:51:52 INFO - PID 1662 | Values: 202.0 199.0 196.0 198.0 203.0 230.0 199.0 195.0 196.0 218.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:88.50 Median:77.50 stddev:29.90 (38.6%) stddev-sans-first:30.96 05:51:52 INFO - PID 1662 | Values: 70.0 82.0 78.0 159.0 65.0 86.0 85.0 77.0 122.0 61.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:71.00 stddev:23.65 (33.3%) stddev-sans-first:24.14 05:51:52 INFO - PID 1662 | Values: 69.0 76.0 125.0 71.0 71.0 78.0 107.0 70.0 77.0 129.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:92.00 stddev:2.49 (2.7%) stddev-sans-first:2.64 05:51:52 INFO - PID 1662 | Values: 92.0 98.0 92.0 91.0 93.0 92.0 92.0 93.0 91.0 88.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:95.50 stddev:19.63 (20.6%) stddev-sans-first:20.79 05:51:52 INFO - PID 1662 | Values: 106.0 136.0 152.0 109.0 96.0 96.0 95.0 96.0 106.0 96.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:91.00 stddev:17.26 (19.0%) stddev-sans-first:18.15 05:51:52 INFO - PID 1662 | Values: 92.0 95.0 91.0 92.0 91.0 86.0 85.0 101.0 106.0 144.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:1.84 (3.5%) stddev-sans-first:1.72 05:51:52 INFO - PID 1662 | Values: 49.0 49.0 49.0 52.0 52.0 53.0 52.0 54.0 52.0 53.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:60.50 stddev:10.77 (17.8%) stddev-sans-first:11.15 05:51:52 INFO - PID 1662 | Values: 57.0 56.0 59.0 64.0 59.0 62.0 84.0 83.0 55.0 58.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.50 stddev:1.75 (2.2%) stddev-sans-first:1.86 05:51:52 INFO - PID 1662 | Values: 79.0 77.0 76.0 79.0 77.0 80.0 81.0 81.0 80.0 78.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:149.50 stddev:14.83 (9.9%) stddev-sans-first:13.06 05:51:52 INFO - PID 1662 | Values: 124.0 175.0 141.0 147.0 154.0 138.0 152.0 135.0 144.0 165.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.70 Median:188.00 stddev:18.40 (9.8%) stddev-sans-first:18.84 05:51:52 INFO - PID 1662 | Values: 177.0 181.0 177.0 177.0 179.0 195.0 216.0 215.0 217.0 173.0 05:51:52 INFO - PID 1662 | 05:51:52 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:67.00 stddev:11.48 (17.1%) stddev-sans-first:12.13 05:51:52 INFO - PID 1662 | Values: 67.0 65.0 66.0 66.0 63.0 67.0 72.0 57.0 77.0 99.0 05:51:52 INFO - PID 1662 | -------- Summary: end -------- 05:51:52 INFO - PID 1662 | 05:51:53 INFO - TEST-INFO | 1662: exit 0 05:51:53 INFO - TEST-OK | kraken | took 89698ms 05:51:53 INFO - SUITE-END | took 1235s 05:51:53 INFO - Completed test suite (00:20:35) 05:51:53 INFO - javascript benchmark 05:51:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8376.46787400562, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2923.4153169366127, 3443.9311213775727, 3561.5013898054262, 3620.9275814483713, 3662.10278481772, 4722.866512037112, 6181.134641150243, 6275.152854364659, 6307.810765677032, 6342.302346741866, 6338.207724034496, 6367.7771278005275, 6378.521610879179, 6384.138141350916, 6396.648184349859, 5515.917261241085, 5563.916541251884, 5577.442255774431, 5590.413006634306, 5622.775088996455, 5401.351837779472, 5466.590005749565, 5561.666300021991, 5578.63738856975, 5580.135079062731, 5448.727563621843, 5462.126059830408, 5494.203340515643, 5501.724913754297, 5561.805336813192, 5550.139728342114, 5551.861203469945, 5554.0835762099605, 5558.027345214612, 5599.440055994409, 5414.187871819219, 5423.918641220384, 5453.100238460649, 5496.148097044925, 5498.9826882026555, 6319.052142178664, 6325.873482530324, 6336.6831658416895, 6346.143270658448, 6359.491240700733, 5069.909969356606, 6552.148220731322, 6734.191896972346, 6840.657967101625, 6883.7590684325805, 5369.436209198017, 6985.266547012541, 7003.299670033007, 7032.648367581601, 7113.608751518665, 4944.901101978012, 6511.218653761579, 6519.380658837403, 6539.803805885831, 6588.901166482507, 6903.309669033057, 7023.719051237893, 7026.894596581055, 7028.648567571601, 7038.648067596702, 5133.922991155061, 6638.767643132467, 6641.800745977628, 6646.567973081735, 6667.166604174478, 6936.028955946172, 6966.616836074065, 6981.511294209458, 6984.650767461607, 6990.790276291719, 5613.915791263134, 6861.348171923659, 6867.313268673192, 6874.828129296808, 6875.656217189121, 6590.505712071614, 6653.866922661617, 6677.09859169006, 6890.724371025103, 6896.103506544117, 5114.846554603368, 5407.972960135174, 5452.727363631802, 5496.203050557647, 5500.862478437991, 3631.2011357501306, 4827.131116399082, 4900.730459824744, 4924.113659541317, 4934.506549345108, 6292.339304373067, 6416.358364163546, 6441.1304473895425, 6454.4513716333695, 6462.935370646327, 6983.057287266154, 7004.984277279766, 7005.054317667152, 7008.439324853999, 7026.297370263035, 4949.208126699711, 6157.168782214344, 6394.26465956421, 6434.935650643527, 6437.517186211053, 5850.297964244232, 5949.196858424144, 5959.553033522503, 5982.222311099529, 5998.220231369894, 5737.627054241461, 6956.373926346552, 6961.6519174042105, 6993.580385176906, 7101.431885449048, 5821.825345239649, 6969.686364113729, 6997.510174287954, 7018.33325834045, 7109.431245500451, 7162.749303774552, 7166.641667916688, 7168.605726685082, 7175.31834475724, 7181.138263408425, 6872.484563657646, 6883.449324054164, 6892.655367231719, 6913.481488888454, 6944.895826562707, 6801.659917004031, 6875.7937261882225, 7021.403180729695, 7067.6112813795735, 7096.787096387116, 5549.361823390362, 6577.769778057745, 6705.262421133769, 6783.118194634666, 6797.592144471348, 6038.154658347747, 6512.772052978123, 6724.529282950147, 6768.289329620319, 6769.526133170631, 6382.6170429774365, 6448.838779030561, 6471.870562588628, 6548.541602087808, 6665.366790154928, 6569.605823650405, 6623.635700036544, 6636.43590294811, 6683.365080317362, 6701.363370479991, 6481.805545833632, 6581.308962559054, 6594.934050659432, 6624.973129164987, 6651.43462805667], "value": 6173.590513369659, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [836.3936146293937, 850.7235148576713, 1193.22440413731, 1232.5686009896538, 1247.2017908538537, 37541.0, 38390.232195356075, 38416.80791596045, 38433.0, 38495.23009539811, 31086.0, 38551.03622409441, 38570.80714596423, 38623.0, 38677.226455470874, 7748.806279842993, 7823.843523129534, 7886.408519361043, 7888.171741967097, 7890.250426209501, 8244.628991695388, 8462.915370846275, 8488.87266690997, 8506.447080939719, 8507.31941444686, 8484.40609157356, 8515.403921725481, 8570.400071994964, 8582.442141260799, 8594.699185528507, 8490.575471226444, 8501.872471912926, 8557.229849313591, 8559.101294364096, 8560.400771945966, 8300.626471808782, 8373.037100733432, 8384.790380240482, 8384.916150838475, 8393.916060839374, 36419.81790091059, 36620.0, 36788.712395066046, 36995.445068324, 37098.443523347174, 3523.6828685418436, 3535.292941411728, 3545.6454354564594, 3546.538949936492, 3547.450145227494, 30080.0, 30112.397752044835, 30135.0, 30157.849210754026, 30194.84902575473, 3039.9816061619385, 3063.2954420483193, 3099.977007587491, 3141.4973604223214, 3146.8426578670974, 331491.0, 332134.0179897328, 332182.0, 333325.0, 334146.0, 38436.8078159613, 39125.0, 39192.2161556773, 47305.76347118242, 47790.76104619524, 50386.74806626017, 50427.24359134616, 50523.747381263594, 50650.74674626677, 50673.0, 15220.0, 15255.160966146968, 15392.384304627689, 15410.0, 15427.84572154264, 12295.0, 12593.30736809466, 12614.684632884251, 12783.61649150527, 12831.35843207836, 13498.93250533741, 13523.85047270993, 13525.45898164082, 13528.86471135276, 13539.255340956144, 4517.3901523293935, 4979.053979743837, 5066.721330326794, 6006.309274433496, 6107.633541987406, 797.720797720793, 919.8823429533015, 1011.0900189829213, 1014.660088870222, 1018.9745256368502, 19771.319437847655, 19823.207071717257, 20422.774633521738, 22357.664635030487, 23222.3033309001, 878.3061381508664, 1063.160103518212, 1068.2682362581502, 1080.3355936099242, 1082.658962426833, 9139.90860091404, 9930.80138397228, 11641.708957276136, 12330.445129969174, 12358.814617780741, 341539.0, 344506.0, 348186.0, 348240.0, 348927.0, 51091.722706932625, 51636.96726065458, 51700.0, 51768.48231517712, 52017.47982520202, 1230.194222784078, 1480.8148981377328, 1486.197453375187, 1488.2335597167466, 1497.9700405992037, 18262.0, 22710.432239194153, 22821.429464263525, 23764.762352376947, 23951.28146155618, 37766.43350349692, 37869.81065094767, 38059.619403805606, 38237.0, 38246.80876595599, 309379.0, 309669.0, 309768.45115774276, 309771.0, 310696.446517766, 27307.863460683366, 27742.02902898389, 27887.581686274316, 32768.67231327656, 33181.50227746538, 34708.0, 34753.82623086868, 39103.02242443848, 39427.80286098666, 39692.603073968894, 26618.0, 27614.861925690242, 27717.0, 29649.25876853096, 30001.0, 29278.414431710822, 29417.0, 29419.0, 29447.116586502438, 29715.257118572208, 32546.83726581352, 33074.83462582672, 33344.49983250205, 34805.65194348024, 34847.303053938274, 25425.872870636267, 28374.0, 29508.557371639872, 29588.704112959455, 29897.70102299036, 19507.12217950167, 23525.294241172793, 23594.646080308466, 23612.645810312515, 23696.52606947886], "value": 17087.96531512223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.0618371472062, 517.2035065998363, 521.4238266715282, 529.8489930369844, 544.5235419008368, 18035.36876209333, 22821.771782282194, 22870.428239294026, 22903.74029428381, 22930.541389172206, 22601.886990565024, 25000.374990625245, 25147.245582632506, 25172.37069073274, 25196.74803251967, 6277.560570760037, 6440.516961227903, 6445.709943052574, 6447.258565264984, 6458.741650333981, 6087.756489740405, 6393.872122557546, 6470.352964703516, 6470.9029364559565, 6474.838129046764, 5173.275741396207, 5806.970965145189, 6363.0773537836985, 6427.614343139403, 6432.163818703562, 6410.711517981678, 6416.0, 6453.9677301613665, 6458.773942911999, 6464.967675161641, 5894.734736936848, 5929.644221346733, 5939.851503712442, 5942.494887934531, 6011.549133814981, 25517.489650207077, 29505.508788066592, 29837.850810746026, 30275.545866812015, 30323.0, 2163.935081947544, 2239.6304609739373, 2263.0495192019225, 2264.4225722440674, 2268.534950335177, 18681.906590467097, 19245.61508769831, 19439.51401214967, 19526.804731952783, 19537.706934395996, 1910.512819231101, 1975.140813746025, 2194.484296190394, 2223.722034745657, 2241.4508445430974, 200470.0, 202872.0, 203019.9698003016, 205460.0, 205853.0, 14532.055416398112, 15482.148481833383, 15635.765463518057, 15722.213889305489, 16224.702023838301, 19108.331208407915, 20458.38624841257, 20752.377428677162, 20773.89613051925, 20778.48053798667, 8593.312534997185, 10202.846957295647, 10274.589016439259, 10372.01465860742, 10426.322289051188, 9646.662366817127, 9682.177014953668, 9752.804943901225, 9803.362781419184, 9813.969533199053, 8448.901642786399, 9172.8624070639, 9290.581923813445, 9345.813083738423, 9359.672411465566, 2602.0892687559203, 2619.9214023579325, 2635.354338187118, 2679.370347968226, 2714.2942834862683, 931.4597533430594, 933.7945651956559, 938.1931538876561, 954.203240294362, 976.4571976277606, 21799.019044143053, 21805.345839624835, 21844.78155218459, 22272.88863555672, 22475.775242247368, 978.1148061004802, 995.3430735714394, 1007.3378924774181, 1025.733309339577, 1034.7361422837128, 9248.150369926041, 11097.778044439066, 11141.610043648434, 11380.374079425546, 11543.595974140864, 581812.1381404311, 590113.0, 593922.0, 604979.0, 615538.0, 154934.0, 163087.0, 164420.0, 165256.0, 167337.3266267346, 1131.801934661431, 1166.7549814538872, 1177.4054102678092, 1182.5742732616252, 1183.2427246562206, 19464.221431143163, 19764.50588735293, 20406.26546743484, 20468.38594842157, 20676.862772547054, 68702.0, 68975.65512172406, 71877.64061179869, 72178.63910680624, 74108.62945685453, 95207.52396238252, 96059.0, 98991.53529626428, 99967.50016250163, 100309.0, 12146.028317734383, 12157.878421215675, 12261.754764904474, 12316.753664926831, 12444.688882778002, 15681.764773528634, 15737.44918927832, 18439.170237339084, 18760.155792989623, 19005.904970475058, 14820.184889831164, 15112.319945602254, 15114.319855606744, 15184.696306074038, 15240.618984525476, 13422.463101475832, 13590.59228223155, 13605.0, 13662.385192666155, 14163.0, 14949.476768313058, 15666.295016724047, 15707.293171807067, 15826.683466330378, 15910.204489775697, 9979.251556133175, 12032.217905836093, 12039.217450865666, 12095.455704493143, 12187.390630468264, 15047.623809404853, 15208.631223189523, 15287.312070956612, 15296.852736045064, 15298.31157597933], "value": 11876.095412579429, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.9019368644197, 1449.9632762574756, 1459.02245495518, 1459.263072148564, 1461.7076584683066, 13415.993800464968, 14026.228557429336, 14064.71870562588, 14066.367013484392, 14083.155010699353, 11624.941875290611, 13629.386677599507, 13896.374663140157, 14045.719085618282, 14059.648508787286, 2415.5168966206743, 2806.130099669099, 2837.6878543360317, 2862.6707928588166, 2870.712928707123, 2457.324235835151, 2716.2801857507775, 2786.0, 2813.6623605167306, 2834.3055951291963, 2794.552871540554, 2848.643919510061, 2856.600075989363, 2862.0984389917107, 2867.541193409055, 2506.6240063990367, 2665.2537289558954, 2794.874230659615, 2824.816386934863, 2829.4906916754976, 2772.639556857616, 2781.29077085344, 2796.66440027196, 2837.404145129525, 2859.599656048155, 13508.392122354519, 13619.931900340534, 13624.659383515393, 13646.931765341209, 13670.726585468334, 7495.550266983998, 7783.260590243918, 7791.181925897812, 7800.297973182441, 7824.726134585262, 4523.547645235483, 4635.351050852883, 4815.277708343741, 4854.417469903634, 4906.092372910987, 1404.5294826233223, 1412.9222892740895, 1415.3701602786764, 1436.956891293263, 1438.5180964376946, 18910.338138165236, 19478.999999999858, 20677.172913083457, 20759.481012974644, 20794.48013799652, 6044.667543285162, 6670.399664030164, 6982.441404687613, 7027.508074434741, 7030.578165310098, 14489.275536223147, 14627.049241799248, 14710.0, 14713.485028023968, 14720.558383248519, 7404.259574042552, 7602.50583712057, 7622.428317876071, 7627.656755446018, 7658.195889431529, 6924.792256232321, 7116.46626503004, 7139.92860071403, 7140.642967851587, 7164.785056448315, 5259.815906443333, 5323.908598737249, 5340.732963351817, 5393.379761327419, 5413.079776438015, 1488.6501672107047, 1493.589262952675, 1497.4309762290477, 1508.4569554960426, 1512.2741084279387, 814.6252723747143, 816.8360086876178, 833.6540335760592, 846.8645016797283, 870.4081224767092, 7048.753293634698, 7191.388731957738, 7396.3343299103335, 7411.444141689395, 7465.440091993122, 652.8379484517567, 677.7255211639196, 684.1345697692423, 704.5737328916034, 717.189575779366, 4556.660631464048, 4752.0, 4839.467658557556, 5140.640155189176, 5354.785808567692, 18300.633987320445, 18539.165737541844, 18702.0, 18906.52733681669, 18948.526286842938, 17928.551786205448, 18286.17712202954, 18335.89984600928, 18576.25694972186, 18777.530561736065, 7690.346320562703, 9268.934072581604, 9628.18160456355, 9704.854427183598, 9757.926628070907, 6756.898646520208, 7312.696500602293, 7532.7740167795055, 7782.727604533814, 7956.681732730857, 16079.437219697254, 16231.918840405722, 16321.673566528365, 16565.503034908972, 16640.50078497647, 16742.995420274823, 17226.483205503853, 17311.826881731526, 17466.388676396266, 17748.645027099643, 10445.791084178425, 10985.231033827875, 11174.161937854531, 11233.831492527783, 11245.831312530156, 12683.87316126827, 12777.23336599807, 12797.0, 12817.679558010752, 12821.294828784507, 10175.28772985884, 10210.642627508001, 10242.283040187413, 10247.077763001365, 10446.320989135682, 8654.653813847628, 9991.700248992542, 10263.0, 10308.072273495422, 10369.014943580349, 9214.44713317203, 9292.117248861347, 9312.25501959828, 9401.388909720847, 9438.339316247797, 6361.7137228823885, 6499.480041596756, 6810.080639113657, 7061.293870612898, 7234.602096884511, 6700.93299067003, 6883.827904302432, 6934.479914006369, 7154.35610795031, 7159.391451726661], "value": 6140.942378127175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.1440134133076, 449.25199542761305, 458.9265717485202, 467.84327250371126, 475.91909375406175, 6308.485963368793, 6569.50728695348, 6654.567453115556, 6675.399214070732, 6690.531662783601, 5430.8913821723545, 6459.700538391611, 6475.90286145707, 6485.2217733871885, 6499.35006499351, 4004.419359192915, 4838.491958344384, 4840.685355451885, 4868.56182943535, 4870.805167793302, 4459.598636122749, 4500.9197792529885, 4536.614387777043, 4811.13399588074, 4825.271234190495, 4315.43899293093, 4698.483166851644, 4776.617870570363, 4777.90444191116, 4803.447603525605, 4430.357598148251, 4441.866743997685, 4495.033567782943, 4786.138495070886, 4802.543758342969, 3681.9447708284397, 4180.979095104535, 4192.24539582875, 4194.8112334945, 4201.789910504463, 6271.686415679198, 6277.152584401093, 6282.842928926768, 6287.622742635411, 6336.58812177207, 5717.857053573653, 5724.8282551523525, 5726.083826587767, 5728.513076388512, 5729.656220626775, 5620.9437905620825, 5724.0, 6917.446604271646, 6973.0, 6983.2318444971015, 4527.0, 4794.808207671689, 4829.855104346875, 4833.29917162013, 4846.854594362175, 6406.583572067801, 7352.63236838156, 7418.517796343274, 7420.888686669704, 7425.0, 4157.792110394468, 4322.8976610964, 4927.384076990376, 4943.950560494421, 4971.726555039508, 3966.722329436912, 4425.291953287458, 4851.151048566487, 5012.924806127911, 5016.072026675077, 4954.950450495521, 5746.166805813134, 5754.568407369464, 5756.33802112754, 5774.595778295479, 7016.36852683261, 7023.473239507058, 7026.262242464468, 7040.204747789281, 7125.42996560274, 1528.2893454543691, 1545.4977132432005, 1655.5861034741315, 1655.9503214903573, 1696.703076961527, 1052.7682611344744, 1068.673601642037, 1144.793937091315, 1159.7912375772441, 1165.953361865516, 5122.924185920924, 5805.477507024304, 6225.719842607094, 6566.244881838554, 6569.244536878225, 7188.712451501881, 7280.563166210044, 7284.629222466584, 7292.671829767673, 7299.5255308404785, 6490.318516555788, 6499.350064993463, 6524.314946930599, 6543.869122617616, 6547.705353259114, 3160.668129846333, 3718.8512459501317, 3768.1333293342136, 3771.0383852117534, 3776.0930729981183, 6241.877761561091, 7447.590382528904, 7468.5145465544565, 7474.701011959461, 7479.364254038359, 7417.592032438268, 7437.479376443705, 7458.0, 7470.252974702486, 7488.925110748932, 5891.262077687093, 7306.452016098708, 7348.448866335153, 7392.889106663404, 7455.403567714569, 7192.676329565077, 7196.784096477114, 7217.530860494051, 7218.386437152901, 7246.96376518114, 6682.887590992991, 7100.964495177491, 7127.750528731677, 7152.284771522806, 7167.964160179165, 6943.861122777617, 7029.3673569379, 7458.813529661802, 7459.701611935679, 7537.54774713519, 4401.383806267061, 4652.790624421841, 4893.724418145437, 4915.674206698082, 4918.827841025547, 7312.378447831994, 7319.7922342812835, 7320.231375705687, 7324.267573242633, 7352.558846469229, 6222.719977601109, 6563.3764792344655, 6985.965070174617, 7005.579665220103, 7014.0, 6305.495560355253, 6924.619145947021, 6936.0, 6943.236244013054, 6976.895346569704, 6448.838779030561, 6768.255491895986, 6779.389854913082, 6789.660516974231, 6801.455883529405, 5868.823935281948, 6342.49260059185, 6916.100906882073, 6944.409725173215, 7003.6848341823725, 5910.822675319747, 6372.661741034434, 6801.489888258499, 6802.829929251809, 6825.897611535732], "value": 5103.871752467296, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.5544906229853, 805.9073206581238, 807.4105902691034, 816.9673213071477, 820.7291593774055, 16679.83320166798, 17163.91418042911, 17253.051082190475, 17255.74116388255, 17382.47852564422, 17505.91247043763, 18793.342233021845, 18835.964021978787, 18948.810511894877, 18949.905250473763, 985.3989066669326, 987.723437437515, 1002.5338217728755, 1010.914072303855, 1014.6702321745425, 991.6529214774844, 997.7405874472616, 999.7500624843789, 1000.4497526360509, 1001.144021861309, 927.9860802087974, 970.6699722094487, 1057.8413238014282, 1061.3419679798535, 1064.914806815453, 971.68905950096, 992.9375568142091, 1040.8282633365486, 1042.801867645145, 1045.7594753206768, 757.121738783009, 757.3486801350834, 757.4054367321626, 757.9393648508161, 760.3612965109335, 17134.0, 17405.651886962318, 17423.431891129858, 17452.825471745247, 17622.471325860246, 17273.56816079608, 17601.471955841218, 17666.293348266056, 17718.379856704953, 17905.641887162314, 14323.35544900482, 14378.496752613713, 14446.783298250535, 14494.34775435108, 14519.709605807824, 3543.025667941337, 3571.4107172316535, 3583.0325812030605, 3597.928041439183, 3601.243738814852, 114028.429857851, 125559.0, 127521.0, 135084.999999999, 138789.0, 13312.800307995194, 14231.43074277038, 14240.501582444565, 14387.92806035963, 14514.637134071521, 26906.73093269042, 26910.192694219204, 27124.59313110305, 27366.179014629593, 27388.58917116245, 8040.15578364275, 8154.817551455125, 8201.30288925448, 8229.753107406787, 8247.587620618946, 4030.435738996543, 4062.0, 4088.9591104089172, 4105.199486100182, 4110.815013324408, 4686.343911852344, 4753.881152971134, 4763.857084287477, 4774.188387974053, 4774.427068751772, 1006.4816619440994, 1042.4943902207424, 1047.2930771729052, 1050.3015494695942, 1051.2536099369381, 587.867729760809, 599.1462166412931, 599.4694695194784, 629.6631302253272, 655.9180102487189, 9468.763280918032, 11068.889311106946, 12040.157188996862, 12052.0, 12268.754624907451, 577.5783677915146, 579.7333226715674, 593.1636392686291, 613.9754409823557, 614.1310046284547, 5086.593072554187, 5323.920141197885, 5338.119210330329, 5546.889062218733, 5619.157126430986, 141684.0, 151880.0, 155254.0, 159250.0, 162028.0, 112939.87060129458, 114805.42597287128, 123141.0, 125615.74384256224, 131141.0, 103590.0, 107731.38402923962, 109565.45217273863, 110290.0, 111467.0, 9213.216876565568, 9423.0, 9972.551235194578, 10520.0, 11093.557837481076, 47349.289760654305, 48147.51852481571, 49432.50567494279, 49615.75192124016, 50267.0, 67450.0, 68532.97200542096, 69814.30185698078, 71566.64216678884, 71952.0, 13979.440822367398, 14985.925070374578, 15145.848541514444, 15172.772408413663, 16588.170591470138, 18809.717854231923, 18960.146793394608, 18992.335268265546, 19125.043747812277, 19319.8068019318, 16924.830751692327, 17160.313587456363, 17910.0, 18060.819391805915, 18261.726074109163, 16822.915885420494, 17402.73895891588, 17404.303827846743, 17586.0, 17841.0, 16516.339346426008, 16890.74663880018, 17076.0, 17272.395466158625, 17378.73931891048, 9579.307926228728, 11081.445927703744, 11342.829857551978, 11979.460924258603, 12422.751544968869, 13561.0, 14213.64465888361, 14302.356393962089, 14379.92810035985, 14675.0], "value": 8796.35014633134, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 111.0, 164.0, 167.0, 169.0, 114.0, 104.0, 114.0, 126.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 130.0, 146.0, 112.0, 125.0, 124.0, 110.0, 157.0, 111.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 199.0, 196.0, 198.0, 203.0, 230.0, 199.0, 195.0, 196.0, 218.0], "value": 203.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 78.0, 159.0, 65.0, 86.0, 85.0, 77.0, 122.0, 61.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 125.0, 71.0, 71.0, 78.0, 107.0, 70.0, 77.0, 129.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 92.0, 91.0, 93.0, 92.0, 92.0, 93.0, 91.0, 88.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 136.0, 152.0, 109.0, 96.0, 96.0, 95.0, 96.0, 106.0, 96.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 91.0, 92.0, 91.0, 86.0, 85.0, 101.0, 106.0, 144.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 52.0, 52.0, 53.0, 52.0, 54.0, 52.0, 53.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 59.0, 64.0, 59.0, 62.0, 84.0, 83.0, 55.0, 58.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 76.0, 79.0, 77.0, 80.0, 81.0, 81.0, 80.0, 78.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 175.0, 141.0, 147.0, 154.0, 138.0, 152.0, 135.0, 144.0, 165.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 181.0, 177.0, 177.0, 179.0, 195.0, 216.0, 215.0, 217.0, 173.0], "value": 190.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 66.0, 63.0, 67.0, 72.0, 57.0, 77.0, 99.0], "value": 69.9, "unit": "score"}]}]} 05:51:53 INFO - Return code: 0 05:51:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:51:53 INFO - # TBPL SUCCESS # 05:51:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:53 INFO - 2017-06-20 05:51:53.768 screenresolution[1670:11077] starting screenresolution argv=screenresolution get 05:51:53 INFO - 2017-06-20 05:51:53.771 screenresolution[1670:11077] Display 0: 1600x1200x32@60 05:51:53 INFO - 2017-06-20 05:51:53.777 screenresolution[1671:11080] starting screenresolution argv=screenresolution list 05:51:53 INFO - Available Modes on Display 0 05:51:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:54 INFO - Intel Iris: 05:51:54 INFO - Chipset Model: Intel Iris 05:51:54 INFO - Type: GPU 05:51:54 INFO - Bus: Built-In 05:51:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:54 INFO - Vendor: Intel (0x8086) 05:51:54 INFO - Device ID: 0x0a2e 05:51:54 INFO - Revision ID: 0x0009 05:51:54 INFO - Displays: 05:51:54 INFO - Display: 05:51:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:54 INFO - Display Serial Number: 1600x1200 60 05:51:54 INFO - Main Display: Yes 05:51:54 INFO - Mirror: Off 05:51:54 INFO - Online: Yes 05:51:54 INFO - Rotation: Supported 05:51:54 INFO - Return code: 0 05:51:54 INFO - Running post-action listener: _resource_record_post_action 05:51:54 INFO - [mozharness: 2017-06-20 12:51:54.014504Z] Finished run-tests step (success) 05:51:54 INFO - Running post-run listener: _resource_record_post_run 05:51:54 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 43973120; Write bytes: 559307776; Read time: 506; Write time: 2017 05:51:54 INFO - TinderboxPrint: CPU usage
53.1% 05:51:54 INFO - TinderboxPrint: I/O read bytes / time
43,973,120 / 506 05:51:54 INFO - TinderboxPrint: I/O write bytes / time
559,307,776 / 2,017 05:51:54 INFO - TinderboxPrint: CPU idle
2,360.9 (46.9%) 05:51:54 INFO - TinderboxPrint: CPU system
1,021.3 (20.3%) 05:51:54 INFO - TinderboxPrint: CPU user
1,647.5 (32.8%) 05:51:54 INFO - TinderboxPrint: Swap in / out
296,181,760 / 0 05:51:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227897856; Write bytes: 227325952; Read time: 16576; Write time: 456 05:51:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:51:54 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 42936832; Write bytes: 313533440; Read time: 482; Write time: 1515 05:51:54 INFO - Running post-run listener: _upload_blobber_files 05:51:54 INFO - Blob upload gear active. 05:51:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:54 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 05:51:54 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:51:54 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:51:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:55 INFO - (blobuploader) - INFO - Done attempting. 05:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:56 INFO - (blobuploader) - INFO - Done attempting. 05:51:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:51:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:51:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:57 INFO - (blobuploader) - INFO - Done attempting. 05:51:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:59 INFO - (blobuploader) - INFO - Done attempting. 05:51:59 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:59 INFO - Return code: 0 05:51:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8df84dbc869e8ae5f4ef95a875b0105b6a8956299ddc11476bc6c661e656617a2506eee512da612a144f24279345d5ace294237b2f4d913dd039b34d309c1234", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/244f7e53c52835ee143cf326e35beaf39b9669b689e60b82118a26288acca25bc3924370d601f827ddd1d542566f0306efd43d64f36b8c9585491f51474fb563", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d4b27297642b4db03e1aca676cb44aca28fa4ecc07b03caa6af87c0d181dce582168b2ec3941298383d2c880ab03985f8027cbbf46ee2626421d20f52913acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/90b63e57b181f3fa9fd0fc285086edf31c9ec65663ff2456595545d5175febac29acdf41a61320de0f80f1057e6d88954a5a234991679b7d8508609087d95fa7"} 05:51:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:59 INFO - Contents: 05:51:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8df84dbc869e8ae5f4ef95a875b0105b6a8956299ddc11476bc6c661e656617a2506eee512da612a144f24279345d5ace294237b2f4d913dd039b34d309c1234", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/244f7e53c52835ee143cf326e35beaf39b9669b689e60b82118a26288acca25bc3924370d601f827ddd1d542566f0306efd43d64f36b8c9585491f51474fb563", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d4b27297642b4db03e1aca676cb44aca28fa4ecc07b03caa6af87c0d181dce582168b2ec3941298383d2c880ab03985f8027cbbf46ee2626421d20f52913acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/90b63e57b181f3fa9fd0fc285086edf31c9ec65663ff2456595545d5175febac29acdf41a61320de0f80f1057e6d88954a5a234991679b7d8508609087d95fa7"} 05:51:59 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:59 INFO - Copying logs to upload dir... 05:51:59 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.068545 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 05:51:59.780040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 05:51:59.783661) ========= 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.LTcQZCqhmt/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.74gmgnLhhf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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/Mozilla-Beta/sha512/8df84dbc869e8ae5f4ef95a875b0105b6a8956299ddc11476bc6c661e656617a2506eee512da612a144f24279345d5ace294237b2f4d913dd039b34d309c1234", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/244f7e53c52835ee143cf326e35beaf39b9669b689e60b82118a26288acca25bc3924370d601f827ddd1d542566f0306efd43d64f36b8c9585491f51474fb563", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d4b27297642b4db03e1aca676cb44aca28fa4ecc07b03caa6af87c0d181dce582168b2ec3941298383d2c880ab03985f8027cbbf46ee2626421d20f52913acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/90b63e57b181f3fa9fd0fc285086edf31c9ec65663ff2456595545d5175febac29acdf41a61320de0f80f1057e6d88954a5a234991679b7d8508609087d95fa7"} build_url:https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011027 build_url: 'https://queue.taskcluster.net/v1/task/Q_BZm_DVRsKgtvNrasTk3w/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8df84dbc869e8ae5f4ef95a875b0105b6a8956299ddc11476bc6c661e656617a2506eee512da612a144f24279345d5ace294237b2f4d913dd039b34d309c1234", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/244f7e53c52835ee143cf326e35beaf39b9669b689e60b82118a26288acca25bc3924370d601f827ddd1d542566f0306efd43d64f36b8c9585491f51474fb563", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d4b27297642b4db03e1aca676cb44aca28fa4ecc07b03caa6af87c0d181dce582168b2ec3941298383d2c880ab03985f8027cbbf46ee2626421d20f52913acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/90b63e57b181f3fa9fd0fc285086edf31c9ec65663ff2456595545d5175febac29acdf41a61320de0f80f1057e6d88954a5a234991679b7d8508609087d95fa7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 05:51:59.816460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 05:51:59.816817) ========= 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.LTcQZCqhmt/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.74gmgnLhhf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005146 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 05:51:59.867775) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 05:51:59.868323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 05:51:59.868886) ========= ========= Total master_lag: 0.17 =========