builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0263 starttime: 1464622531.43 results: success (0) buildid: 20160530063440 builduid: 62d3bf00a5f2423ea9dd05da04045db7 revision: 7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.430653) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.431150) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.431438) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.455522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.455806) ========= 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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 --2016-05-30 08:35:31-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2016-05-30 08:35:31 (18.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098012 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.573733) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.574047) ========= 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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.025240 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:31.622375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 08:35:31.622717) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 2016-05-30 08:35:31,700 truncating revision to first 12 chars 2016-05-30 08:35:31,700 Setting DEBUG logging. 2016-05-30 08:35:31,700 attempt 1/10 2016-05-30 08:35:31,700 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7dcc890c12a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 08:35:33,079 unpacking tar archive at: fx-team-7dcc890c12a8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.907879 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 08:35:33.550453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:33.550835) ========= script_repo_revision: 7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:33.551293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:33.551595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:35:33.567707) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-30 08:35:33.568053) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:35:33 INFO - MultiFileLogger online at 20160530 08:35:33 in /builds/slave/test 08:35:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:35:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:35:33 INFO - {'append_to_log': False, 08:35:33 INFO - 'base_work_dir': '/builds/slave/test', 08:35:33 INFO - 'blob_upload_branch': 'Fx-Team', 08:35:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:35:33 INFO - 'branch': 'Fx-Team', 08:35:33 INFO - 'buildbot_json_path': 'buildprops.json', 08:35:33 INFO - 'config_files': ('talos/mac_config.py',), 08:35:33 INFO - 'default_actions': ('clobber', 08:35:33 INFO - 'read-buildbot-config', 08:35:33 INFO - 'download-and-extract', 08:35:33 INFO - 'populate-webroot', 08:35:33 INFO - 'create-virtualenv', 08:35:33 INFO - 'install', 08:35:33 INFO - 'run-tests'), 08:35:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:35:33 INFO - 'download_minidump_stackwalk': True, 08:35:33 INFO - 'download_symbols': 'ondemand', 08:35:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:35:33 INFO - 'tooltool.py': '/tools/tooltool.py', 08:35:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:35:33 INFO - '/tools/misc-python/virtualenv.py')}, 08:35:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:35:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:35:33 INFO - 'installer_path': 'installer.exe', 08:35:33 INFO - 'log_level': 'info', 08:35:33 INFO - 'log_name': 'talos', 08:35:33 INFO - 'log_to_console': True, 08:35:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:35:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:35:33 INFO - 'opt_config_files': (), 08:35:33 INFO - 'pip_index': False, 08:35:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:35:33 INFO - 'cmd': ('bash', 08:35:33 INFO - '-c', 08:35:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:35:33 INFO - 'enabled': True, 08:35:33 INFO - 'halt_on_failure': False, 08:35:33 INFO - 'name': 'check_screen_resolution'},), 08:35:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:35:33 INFO - 'cmd': ('bash', 08:35:33 INFO - '-c', 08:35:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:35:33 INFO - 'enabled': True, 08:35:33 INFO - 'halt_on_failure': False, 08:35:33 INFO - 'name': 'check_screen_resolution'},), 08:35:33 INFO - 'run_cmd_checks_enabled': True, 08:35:33 INFO - 'sps_profile': False, 08:35:33 INFO - 'sps_profile_interval': 0, 08:35:33 INFO - 'suite': 'dromaeojs', 08:35:33 INFO - 'system_bits': '32', 08:35:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:35:33 INFO - 'title': 't-yosemite-r7-0263', 08:35:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:35:33 INFO - 'use_talos_json': True, 08:35:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:35:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:35:33 INFO - 'work_dir': 'build'} 08:35:33 INFO - ##### 08:35:33 INFO - ##### Running clobber step. 08:35:33 INFO - ##### 08:35:33 INFO - Running pre-action listener: _resource_record_pre_action 08:35:33 INFO - Running main action method: clobber 08:35:33 INFO - rmtree: /builds/slave/test/build 08:35:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:35:35 INFO - Running post-action listener: _resource_record_post_action 08:35:35 INFO - ##### 08:35:35 INFO - ##### Finished clobber step (success) 08:35:35 INFO - ##### 08:35:35 INFO - ##### 08:35:35 INFO - ##### Running read-buildbot-config step. 08:35:35 INFO - ##### 08:35:35 INFO - Running pre-action listener: _resource_record_pre_action 08:35:35 INFO - Running main action method: read_buildbot_config 08:35:35 INFO - Using buildbot properties: 08:35:35 INFO - { 08:35:35 INFO - "project": "", 08:35:35 INFO - "product": "firefox", 08:35:35 INFO - "script_repo_revision": "production", 08:35:35 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:35:35 INFO - "repository": "", 08:35:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:35:35 INFO - "buildid": "20160530063440", 08:35:35 INFO - "pgo_build": "False", 08:35:35 INFO - "basedir": "/builds/slave/test", 08:35:35 INFO - "buildnumber": 728, 08:35:35 INFO - "slavename": "t-yosemite-r7-0263", 08:35:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:35:35 INFO - "platform": "yosemite_r7", 08:35:35 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:35:35 INFO - "branch": "fx-team", 08:35:35 INFO - "repo_path": "integration/fx-team", 08:35:35 INFO - "revision": "7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b", 08:35:35 INFO - "stage_platform": "macosx64", 08:35:35 INFO - "builduid": "62d3bf00a5f2423ea9dd05da04045db7", 08:35:35 INFO - "slavebuilddir": "test" 08:35:35 INFO - } 08:35:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:35:35 INFO - Running post-action listener: _resource_record_post_action 08:35:35 INFO - ##### 08:35:35 INFO - ##### Finished read-buildbot-config step (success) 08:35:35 INFO - ##### 08:35:35 INFO - ##### 08:35:35 INFO - ##### Running download-and-extract step. 08:35:35 INFO - ##### 08:35:35 INFO - Running pre-action listener: _resource_record_pre_action 08:35:35 INFO - Running main action method: download_and_extract 08:35:35 INFO - mkdir: /builds/slave/test/build/tests 08:35:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:35 INFO - https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:35:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:35:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:35:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:35:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 08:35:36 INFO - Downloaded 1199 bytes. 08:35:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:35:36 INFO - Using the following test package requirements: 08:35:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:35:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:35:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:35:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'jsshell-mac.zip'], 08:35:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:35:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:35:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:35:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:35:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:35:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:35:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:35:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:36 INFO - https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:35:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:35:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:35:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:35:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:35:38 INFO - Downloaded 18684742 bytes. 08:35:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:35:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:35:41 INFO - Return code: 0 08:35:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:41 INFO - https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:35:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:35:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:35:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:35:42 INFO - Downloaded 18684742 bytes. 08:35:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:35:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:35:45 INFO - Return code: 0 08:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:45 INFO - https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:35:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:35:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:35:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:35:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:35:46 INFO - Downloaded 10834055 bytes. 08:35:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:35:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:35:46 INFO - Return code: 0 08:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:46 INFO - https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:35:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:35:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:35:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:35:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 08:35:50 INFO - Downloaded 110901194 bytes. 08:35:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:35:50 INFO - mkdir: /builds/slave/test/properties 08:35:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:35:50 INFO - Writing to file /builds/slave/test/properties/build_url 08:35:50 INFO - Contents: 08:35:50 INFO - build_url:https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:35:51 INFO - Running post-action listener: _resource_record_post_action 08:35:51 INFO - Running post-action listener: set_extra_try_arguments 08:35:51 INFO - ##### 08:35:51 INFO - ##### Finished download-and-extract step (success) 08:35:51 INFO - ##### 08:35:51 INFO - ##### 08:35:51 INFO - ##### Running populate-webroot step. 08:35:51 INFO - ##### 08:35:51 INFO - Running pre-action listener: _resource_record_pre_action 08:35:51 INFO - Running main action method: populate_webroot 08:35:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:35:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:35:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:35:51 INFO - '--tppagecycles', 08:35:51 INFO - '7'], 08:35:51 INFO - 'tests': ['tsvgm']}}, 08:35:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:35:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:35:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:35:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:35:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['damp', 'tps']}, 08:35:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'tests': ['damp', 'tps']}, 08:35:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['dromaeo_dom']}, 08:35:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:35:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['a11yr', 08:35:51 INFO - 'ts_paint', 08:35:51 INFO - 'tpaint', 08:35:51 INFO - 'sessionrestore', 08:35:51 INFO - 'sessionrestore_no_auto_restore', 08:35:51 INFO - 'tabpaint']}, 08:35:51 INFO - 'other-e10s': {'tests': ['a11yr', 08:35:51 INFO - 'ts_paint', 08:35:51 INFO - 'tpaint', 08:35:51 INFO - 'sessionrestore', 08:35:51 INFO - 'sessionrestore_no_auto_restore', 08:35:51 INFO - 'tabpaint']}, 08:35:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:35:51 INFO - 'ts_paint', 08:35:51 INFO - 'tpaint', 08:35:51 INFO - 'sessionrestore', 08:35:51 INFO - 'sessionrestore_no_auto_restore', 08:35:51 INFO - 'tabpaint']}, 08:35:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:35:51 INFO - 'ts_paint', 08:35:51 INFO - 'tpaint', 08:35:51 INFO - 'sessionrestore', 08:35:51 INFO - 'sessionrestore_no_auto_restore', 08:35:51 INFO - 'tabpaint']}, 08:35:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['a11yr', 08:35:51 INFO - 'ts_paint', 08:35:51 INFO - 'tpaint', 08:35:51 INFO - 'sessionrestore', 08:35:51 INFO - 'sessionrestore_no_auto_restore', 08:35:51 INFO - 'tabpaint']}, 08:35:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['a11yr', 08:35:51 INFO - 'ts_paint', 08:35:51 INFO - 'tpaint', 08:35:51 INFO - 'sessionrestore', 08:35:51 INFO - 'sessionrestore_no_auto_restore', 08:35:51 INFO - 'tabpaint']}, 08:35:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['tsvgx', 08:35:51 INFO - 'tsvgr_opacity', 08:35:51 INFO - 'tart', 08:35:51 INFO - 'tscrollx', 08:35:51 INFO - 'cart']}, 08:35:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:35:51 INFO - 'tsvgr_opacity', 08:35:51 INFO - 'tart', 08:35:51 INFO - 'tscrollx', 08:35:51 INFO - 'cart']}, 08:35:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'talos_options': ['--disable-e10s'], 08:35:51 INFO - 'tests': ['tp5o']}, 08:35:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'tests': ['tp5o']}, 08:35:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'talos_options': ['--disable-e10s', 08:35:51 INFO - '--xperf_path', 08:35:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:51 INFO - 'tests': ['tp5n']}, 08:35:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:51 INFO - 'talos_options': ['--xperf_path', 08:35:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:51 INFO - 'tests': ['tp5n']}}, 08:35:51 INFO - 'talos.zip': {'path': '', 08:35:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:35:51 INFO - Running post-action listener: _resource_record_post_action 08:35:51 INFO - ##### 08:35:51 INFO - ##### Finished populate-webroot step (success) 08:35:51 INFO - ##### 08:35:51 INFO - ##### 08:35:51 INFO - ##### Running create-virtualenv step. 08:35:51 INFO - ##### 08:35:51 INFO - Running pre-action listener: _resource_record_pre_action 08:35:51 INFO - Running main action method: create_virtualenv 08:35:51 INFO - Creating virtualenv /builds/slave/test/build/venv 08:35:51 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 08:35:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:35:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:35:51 INFO - Using real prefix '/tools/python27' 08:35:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:35:51 INFO - Installing distribute.............................................................................................................................................................................................done. 08:35:54 INFO - Installing pip.................done. 08:35:54 INFO - Return code: 0 08:35:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:35:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:35:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:35:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:35:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:54 INFO - 'HOME': '/Users/cltbld', 08:35:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:54 INFO - 'LOGNAME': 'cltbld', 08:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:54 INFO - 'MOZ_NO_REMOTE': '1', 08:35:54 INFO - 'NO_EM_RESTART': '1', 08:35:54 INFO - 'PAGER': '/bin/cat', 08:35:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:54 INFO - 'PWD': '/builds/slave/test', 08:35:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:54 INFO - 'SHELL': '/bin/bash', 08:35:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:35:54 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:35:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:54 INFO - 'USER': 'cltbld', 08:35:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:54 INFO - 'XPC_FLAGS': '0x0', 08:35:54 INFO - 'XPC_SERVICE_NAME': '0', 08:35:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:55 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 08:35:55 INFO - Cleaning up... 08:35:55 INFO - Return code: 0 08:35:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:35:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:35:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:35:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:35:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:55 INFO - 'HOME': '/Users/cltbld', 08:35:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:55 INFO - 'LOGNAME': 'cltbld', 08:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:55 INFO - 'MOZ_NO_REMOTE': '1', 08:35:55 INFO - 'NO_EM_RESTART': '1', 08:35:55 INFO - 'PAGER': '/bin/cat', 08:35:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:55 INFO - 'PWD': '/builds/slave/test', 08:35:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:55 INFO - 'SHELL': '/bin/bash', 08:35:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:35:55 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:35:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:55 INFO - 'USER': 'cltbld', 08:35:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:55 INFO - 'XPC_FLAGS': '0x0', 08:35:55 INFO - 'XPC_SERVICE_NAME': '0', 08:35:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:55 INFO - Downloading/unpacking psutil>=3.1.1 08:35:55 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 08:35:55 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 08:35:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:55 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 08:35:55 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 08:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:35:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:58 INFO - Installing collected packages: psutil 08:35:58 INFO - Running setup.py install for psutil 08:35:58 INFO - building 'psutil._psutil_osx' extension 08:35:58 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 08:35:58 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 08:35:58 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 08:35:58 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 08:35:59 INFO - building 'psutil._psutil_posix' extension 08:35:59 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 08:35:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:35:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:35:59 INFO - ^ 08:35:59 INFO - 1 warning generated. 08:35:59 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 08:35:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:59 INFO - Successfully installed psutil 08:35:59 INFO - Cleaning up... 08:35:59 INFO - Return code: 0 08:35:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:59 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:35:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:35:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:35:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:59 INFO - 'HOME': '/Users/cltbld', 08:35:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:59 INFO - 'LOGNAME': 'cltbld', 08:35:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:59 INFO - 'MOZ_NO_REMOTE': '1', 08:35:59 INFO - 'NO_EM_RESTART': '1', 08:35:59 INFO - 'PAGER': '/bin/cat', 08:35:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:59 INFO - 'PWD': '/builds/slave/test', 08:35:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:59 INFO - 'SHELL': '/bin/bash', 08:35:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:35:59 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:35:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:59 INFO - 'USER': 'cltbld', 08:35:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:59 INFO - 'XPC_FLAGS': '0x0', 08:35:59 INFO - 'XPC_SERVICE_NAME': '0', 08:35:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:35:59 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 08:35:59 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 08:35:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:59 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 08:35:59 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 08:36:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:36:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:36:02 INFO - Installing collected packages: mozsystemmonitor 08:36:02 INFO - Running setup.py install for mozsystemmonitor 08:36:02 INFO - Successfully installed mozsystemmonitor 08:36:02 INFO - Cleaning up... 08:36:02 INFO - Return code: 0 08:36:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:36:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:02 INFO - 'HOME': '/Users/cltbld', 08:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:02 INFO - 'LOGNAME': 'cltbld', 08:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:02 INFO - 'MOZ_NO_REMOTE': '1', 08:36:02 INFO - 'NO_EM_RESTART': '1', 08:36:02 INFO - 'PAGER': '/bin/cat', 08:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:02 INFO - 'PWD': '/builds/slave/test', 08:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:02 INFO - 'SHELL': '/bin/bash', 08:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:36:02 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:02 INFO - 'USER': 'cltbld', 08:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:02 INFO - 'XPC_FLAGS': '0x0', 08:36:02 INFO - 'XPC_SERVICE_NAME': '0', 08:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:02 INFO - Downloading/unpacking blobuploader==1.2.4 08:36:02 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 08:36:02 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 08:36:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:02 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 08:36:02 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 08:36:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:36:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:36:05 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 08:36:05 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 08:36:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:05 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 08:36:05 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 08:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:36:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:36:05 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 08:36:05 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 08:36:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:05 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 08:36:05 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 08:36:06 INFO - Downloading docopt-0.6.1.tar.gz 08:36:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:36:06 INFO - Installing collected packages: blobuploader, requests, docopt 08:36:06 INFO - Running setup.py install for blobuploader 08:36:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:36:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:36:06 INFO - Running setup.py install for requests 08:36:06 INFO - Running setup.py install for docopt 08:36:07 INFO - Successfully installed blobuploader requests docopt 08:36:07 INFO - Cleaning up... 08:36:07 INFO - Return code: 0 08:36:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:36:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:36:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:36:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:36:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:07 INFO - 'HOME': '/Users/cltbld', 08:36:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:07 INFO - 'LOGNAME': 'cltbld', 08:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:07 INFO - 'MOZ_NO_REMOTE': '1', 08:36:07 INFO - 'NO_EM_RESTART': '1', 08:36:07 INFO - 'PAGER': '/bin/cat', 08:36:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:07 INFO - 'PWD': '/builds/slave/test', 08:36:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:07 INFO - 'SHELL': '/bin/bash', 08:36:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:36:07 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:36:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:07 INFO - 'USER': 'cltbld', 08:36:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:07 INFO - 'XPC_FLAGS': '0x0', 08:36:07 INFO - 'XPC_SERVICE_NAME': '0', 08:36:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:36:07 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-C27ZBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:36:07 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-eAShSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:36:07 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-UsCH0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:36:07 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Gqnjt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:36:07 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-NgNAPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:36:07 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-8YHzU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-404Pl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-wbBQJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rzjNer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-bEz_AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-6M4b79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-wjphwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-o8KdI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rkFFeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:08 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-wW7bBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:36:09 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-URqZfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:36:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:36:09 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-9tHVe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:36:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:36:09 INFO - Running setup.py install for manifestparser 08:36:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:36:09 INFO - Running setup.py install for mozcrash 08:36:09 INFO - Running setup.py install for mozdebug 08:36:09 INFO - Running setup.py install for mozdevice 08:36:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:36:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:36:09 INFO - Running setup.py install for mozfile 08:36:09 INFO - Running setup.py install for mozhttpd 08:36:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Running setup.py install for mozinfo 08:36:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Running setup.py install for mozInstall 08:36:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Running setup.py install for mozleak 08:36:10 INFO - Running setup.py install for mozlog 08:36:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Running setup.py install for moznetwork 08:36:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:36:10 INFO - Running setup.py install for mozprocess 08:36:10 INFO - Running setup.py install for mozprofile 08:36:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:36:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:36:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:36:11 INFO - Running setup.py install for mozrunner 08:36:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:36:11 INFO - Running setup.py install for mozscreenshot 08:36:11 INFO - Running setup.py install for moztest 08:36:11 INFO - Running setup.py install for mozversion 08:36:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:36:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:36:11 INFO - Cleaning up... 08:36:11 INFO - Return code: 0 08:36:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:36:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:36:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:11 INFO - 'HOME': '/Users/cltbld', 08:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:11 INFO - 'LOGNAME': 'cltbld', 08:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:11 INFO - 'MOZ_NO_REMOTE': '1', 08:36:11 INFO - 'NO_EM_RESTART': '1', 08:36:11 INFO - 'PAGER': '/bin/cat', 08:36:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:11 INFO - 'PWD': '/builds/slave/test', 08:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:11 INFO - 'SHELL': '/bin/bash', 08:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:36:11 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:11 INFO - 'USER': 'cltbld', 08:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:11 INFO - 'XPC_FLAGS': '0x0', 08:36:11 INFO - 'XPC_SERVICE_NAME': '0', 08:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-kOqJRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:36:12 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-JY92Jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YMij5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:36:12 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-0kmHsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3tL4ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:36:12 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-M7lRGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:36:12 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-nP4Rnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-fs5EpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:36:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-SWdKlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:36:12 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)) 08:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-8bXW9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:36:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-vjHz3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:36:13 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-bxxB8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:36:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-JJQd_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:36:13 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Y_uGBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:36:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-r1F65K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:13 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-j12HNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:36:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 08:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:36:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Sj20P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:36:13 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)) 08:36:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:36:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:36:13 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)) 08:36:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:36:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:36:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:36:13 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 08:36:13 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 08:36:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:13 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 08:36:13 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 08:36:16 INFO - Downloading blessings-1.6.tar.gz 08:36:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:36:16 INFO - Installing collected packages: blessings 08:36:16 INFO - Running setup.py install for blessings 08:36:16 INFO - Successfully installed blessings 08:36:16 INFO - Cleaning up... 08:36:16 INFO - Return code: 0 08:36:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:36:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:36:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:36:16 INFO - Reading from file tmpfile_stdout 08:36:16 INFO - Current package versions: 08:36:16 INFO - blessings == 1.6 08:36:16 INFO - blobuploader == 1.2.4 08:36:16 INFO - docopt == 0.6.1 08:36:16 INFO - manifestparser == 1.1 08:36:16 INFO - mozInstall == 1.12 08:36:16 INFO - mozcrash == 0.17 08:36:16 INFO - mozdebug == 0.1 08:36:16 INFO - mozdevice == 0.48 08:36:16 INFO - mozfile == 1.2 08:36:16 INFO - mozhttpd == 0.7 08:36:16 INFO - mozinfo == 0.9 08:36:16 INFO - mozleak == 0.1 08:36:16 INFO - mozlog == 3.2 08:36:16 INFO - moznetwork == 0.27 08:36:16 INFO - mozprocess == 0.22 08:36:16 INFO - mozprofile == 0.28 08:36:16 INFO - mozrunner == 6.11 08:36:16 INFO - mozscreenshot == 0.1 08:36:16 INFO - mozsystemmonitor == 0.3 08:36:16 INFO - moztest == 0.7 08:36:16 INFO - mozversion == 1.4 08:36:16 INFO - psutil == 3.1.1 08:36:16 INFO - requests == 1.2.3 08:36:16 INFO - wsgiref == 0.1.2 08:36:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:36:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:36:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:16 INFO - 'HOME': '/Users/cltbld', 08:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:16 INFO - 'LOGNAME': 'cltbld', 08:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:16 INFO - 'MOZ_NO_REMOTE': '1', 08:36:16 INFO - 'NO_EM_RESTART': '1', 08:36:16 INFO - 'PAGER': '/bin/cat', 08:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:16 INFO - 'PWD': '/builds/slave/test', 08:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:16 INFO - 'SHELL': '/bin/bash', 08:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:36:16 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:16 INFO - 'USER': 'cltbld', 08:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:16 INFO - 'XPC_FLAGS': '0x0', 08:36:16 INFO - 'XPC_SERVICE_NAME': '0', 08:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 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)) 08:36:17 INFO - Cleaning up... 08:36:17 INFO - Return code: 0 08:36:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:36:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:36:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:36:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:36:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:36:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:17 INFO - 'HOME': '/Users/cltbld', 08:36:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:17 INFO - 'LOGNAME': 'cltbld', 08:36:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:17 INFO - 'MOZ_NO_REMOTE': '1', 08:36:17 INFO - 'NO_EM_RESTART': '1', 08:36:17 INFO - 'PAGER': '/bin/cat', 08:36:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:17 INFO - 'PWD': '/builds/slave/test', 08:36:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:17 INFO - 'SHELL': '/bin/bash', 08:36:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:36:17 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:36:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:17 INFO - 'USER': 'cltbld', 08:36:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:17 INFO - 'XPC_FLAGS': '0x0', 08:36:17 INFO - 'XPC_SERVICE_NAME': '0', 08:36:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:17 INFO - Downloading/unpacking jsonschema 08:36:17 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 08:36:17 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 08:36:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:17 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 08:36:17 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 08:36:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:36:19 INFO - Downloading/unpacking functools32 (from jsonschema) 08:36:19 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 08:36:19 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 08:36:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:19 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 08:36:19 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 08:36:20 INFO - Downloading functools32-3.2.3-2.tar.gz 08:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:36:20 INFO - warning: no files found matching '*.txt' 08:36:20 INFO - no previously-included directories found matching 'build' 08:36:20 INFO - no previously-included directories found matching 'dist' 08:36:20 INFO - no previously-included directories found matching '.git*' 08:36:20 INFO - Installing collected packages: jsonschema, functools32 08:36:20 INFO - Running setup.py install for functools32 08:36:20 INFO - warning: no files found matching '*.txt' 08:36:20 INFO - no previously-included directories found matching 'build' 08:36:20 INFO - no previously-included directories found matching 'dist' 08:36:20 INFO - no previously-included directories found matching '.git*' 08:36:20 INFO - Successfully installed jsonschema functools32 08:36:20 INFO - Cleaning up... 08:36:20 INFO - Return code: 0 08:36:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad485af590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, '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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:20 INFO - 'HOME': '/Users/cltbld', 08:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:20 INFO - 'LOGNAME': 'cltbld', 08:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:20 INFO - 'MOZ_NO_REMOTE': '1', 08:36:20 INFO - 'NO_EM_RESTART': '1', 08:36:20 INFO - 'PAGER': '/bin/cat', 08:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:20 INFO - 'PWD': '/builds/slave/test', 08:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:20 INFO - 'SHELL': '/bin/bash', 08:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:36:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:20 INFO - 'USER': 'cltbld', 08:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:20 INFO - 'XPC_FLAGS': '0x0', 08:36:20 INFO - 'XPC_SERVICE_NAME': '0', 08:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:20 INFO - Downloading/unpacking flake8 08:36:20 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 08:36:20 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 08:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:20 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 08:36:20 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 08:36:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:36:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:36:23 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 08:36:23 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 08:36:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:23 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 08:36:23 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 08:36:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:36:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:36:23 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 08:36:23 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 08:36:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:23 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 08:36:23 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 08:36:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:36:24 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 08:36:24 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 08:36:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:24 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 08:36:24 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 08:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:36:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:36:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:36:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:36:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:36:24 INFO - no previously-included directories found matching 'docs/_build' 08:36:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:36:24 INFO - Running setup.py install for pep8 08:36:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:36:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:36:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:36:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:36:24 INFO - no previously-included directories found matching 'docs/_build' 08:36:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:36:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:36:24 INFO - Cleaning up... 08:36:24 INFO - Return code: 0 08:36:24 INFO - Running post-action listener: _resource_record_post_action 08:36:24 INFO - Running post-action listener: _start_resource_monitoring 08:36:25 INFO - Starting resource monitoring. 08:36:25 INFO - ##### 08:36:25 INFO - ##### Finished create-virtualenv step (success) 08:36:25 INFO - ##### 08:36:25 INFO - ##### 08:36:25 INFO - ##### Running install step. 08:36:25 INFO - ##### 08:36:25 INFO - Running pre-action listener: _resource_record_pre_action 08:36:25 INFO - Running main action method: install 08:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:36:25 INFO - Reading from file tmpfile_stdout 08:36:25 INFO - Detecting whether we're running mozinstall >=1.0... 08:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:36:25 INFO - Reading from file tmpfile_stdout 08:36:25 INFO - Output received: 08:36:25 INFO - Usage: mozinstall [options] installer 08:36:25 INFO - Options: 08:36:25 INFO - -h, --help show this help message and exit 08:36:25 INFO - -d DEST, --destination=DEST 08:36:25 INFO - Directory to install application into. [default: 08:36:25 INFO - "/builds/slave/test"] 08:36:25 INFO - --app=APP Application being installed. [default: firefox] 08:36:25 INFO - mkdir: /builds/slave/test/build/application 08:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:36:59 INFO - Reading from file tmpfile_stdout 08:36:59 INFO - Output received: 08:36:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:36:59 INFO - Running post-action listener: _resource_record_post_action 08:36:59 INFO - ##### 08:36:59 INFO - ##### Finished install step (success) 08:36:59 INFO - ##### 08:36:59 INFO - ##### 08:36:59 INFO - ##### Running run-tests step. 08:36:59 INFO - ##### 08:36:59 INFO - Running pre-action listener: _resource_record_pre_action 08:36:59 INFO - Running main action method: run_tests 08:36:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:59 INFO - 2016-05-30 08:36:59.148 screenresolution[1642:7474] starting screenresolution argv=screenresolution get 08:36:59 INFO - 2016-05-30 08:36:59.150 screenresolution[1642:7474] Display 0: 1600x1200x32@60 08:36:59 INFO - 2016-05-30 08:36:59.156 screenresolution[1643:7476] starting screenresolution argv=screenresolution list 08:36:59 INFO - Available Modes on Display 0 08:36:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:59 INFO - Intel Iris: 08:36:59 INFO - Chipset Model: Intel Iris 08:36:59 INFO - Type: GPU 08:36:59 INFO - Bus: Built-In 08:36:59 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:59 INFO - Vendor: Intel (0x8086) 08:36:59 INFO - Device ID: 0x0a2e 08:36:59 INFO - Revision ID: 0x0009 08:36:59 INFO - Displays: 08:36:59 INFO - Display: 08:36:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:59 INFO - Display Serial Number: 1600x1200 60 08:36:59 INFO - Main Display: Yes 08:36:59 INFO - Mirror: Off 08:36:59 INFO - Online: Yes 08:36:59 INFO - Rotation: Supported 08:36:59 INFO - Return code: 0 08:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:36:59 INFO - Python 2.7.3 08:36:59 INFO - Return code: 0 08:36:59 INFO - grabbing minidump binary from tooltool 08:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:59 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 0x10dae6d30>, '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 0x10d90adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10daddb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:36:59 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 08:36:59 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 08:36:59 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 08:36:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:36:59 INFO - Return code: 0 08:36:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:36:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:36:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:36:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:36:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:37:00 INFO - Return code: 0 08:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:37:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf7owFzfvZ/Render', 08:37:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:00 INFO - 'HOME': '/Users/cltbld', 08:37:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:00 INFO - 'LOGNAME': 'cltbld', 08:37:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:37:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:00 INFO - 'MOZ_NO_REMOTE': '1', 08:37:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:37:00 INFO - 'NO_EM_RESTART': '1', 08:37:00 INFO - 'PAGER': '/bin/cat', 08:37:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:00 INFO - 'PWD': '/builds/slave/test', 08:37:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:37:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:00 INFO - 'SHELL': '/bin/bash', 08:37:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LsQOqdtxqF/Listeners', 08:37:00 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:37:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:00 INFO - 'USER': 'cltbld', 08:37:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:00 INFO - 'XPC_FLAGS': '0x0', 08:37:00 INFO - 'XPC_SERVICE_NAME': '0', 08:37:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:37:00 INFO - mozversion application_buildid: 20160530063440 08:37:00 INFO - mozversion application_changeset: 7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b 08:37:00 INFO - mozversion application_display_name: Nightly 08:37:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:37:00 INFO - mozversion application_name: Firefox 08:37:00 INFO - mozversion application_remotingname: firefox 08:37:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:37:00 INFO - mozversion application_vendor: Mozilla 08:37:00 INFO - mozversion application_version: 49.0a1 08:37:00 INFO - mozversion platform_buildid: 20160530063440 08:37:00 INFO - mozversion platform_changeset: 7dcc890c12a81ad6ce5ae9ff5a29eccf9137112b 08:37:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:37:00 INFO - mozversion platform_version: 49.0a1 08:37:00 INFO - using testdate: 1464622620 08:37:00 INFO - actual date: 1464622620 08:37:00 INFO - starting webserver on 'localhost:49277' 08:37:00 INFO - SUITE-START | Running 2 tests 08:37:00 INFO - TEST-START | dromaeo_css 08:37:00 INFO - operating with platform_type : mac_ 08:37:00 INFO - Initialising browser for dromaeo_css test... 08:37:00 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpAb8GDf/profile http://localhost:49277/getInfo.html) 08:37:03 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 08:37:03 INFO - PROCESS | 1661 | colorDepth:24 08:37:03 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 08:37:03 INFO - PROCESS | 1661 | __metrics 08:37:03 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:37:03 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:37:04 INFO - TEST-INFO | 1661: exit 0 08:37:04 INFO - Browser initialized. 08:37:04 INFO - Running cycle 1/1 for dromaeo_css test... 08:37:04 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpAb8GDf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:37:16 INFO - PROCESS | 1663 | RSS: Main: 241856512 08:37:16 INFO - PROCESS | 1663 | 08:38:07 INFO - PROCESS | 1663 | 1464622687710 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:40:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 08:40:19 INFO - PROCESS | 1663 | RSS: Main: 351416320 08:40:19 INFO - PROCESS | 1663 | 08:43:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 08:43:23 INFO - PROCESS | 1663 | RSS: Main: 384270336 08:43:23 INFO - PROCESS | 1663 | 08:46:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 08:46:26 INFO - PROCESS | 1663 | RSS: Main: 373092352 08:46:26 INFO - PROCESS | 1663 | 08:49:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 08:49:29 INFO - PROCESS | 1663 | RSS: Main: 372088832 08:49:29 INFO - PROCESS | 1663 | 08:52:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:52:32 INFO - PROCESS | 1663 | RSS: Main: 339582976 08:52:32 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:55:36 INFO - PROCESS | 1663 | RSS: Main: 381132800 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | __start_tp_report 08:55:36 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 08:55:36 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 08:55:36 INFO - PROCESS | 1663 | |i|pagename|runs| 08:55:36 INFO - PROCESS | 1663 | |0;dojo.html;3307.0244277938023;3415.641357657446;3423.5720534933134;3431.4338134207856;3484.1812174139072;5540.722963851801;5594.272744543204;5619.129034999576;5657.519110875575;5677.659340439576;5609.35492418371;5665.433456654332;5666.348369937458;5671.432856714327;5681.091025435931;5078.212877004061;5112.744362781865;5136.845894623168;5141.126008578532;5197.766100525486;5162.8193013244545;5178.792848286064;5183.53348198662;5193.16909294513;5210.218467229908;5103.668261563005;5131.820386286481;5137.38351397831;5137.897242055156;5192.013517431676;5188.455212202721;5220.034293655686;5229.346331708536;5233.528982391565;5265.262863199156;4985.227289770091;5004.199328107522;5051.393832740095;5106.157484015133;5115.2071428928575;5524.171374293888;5541.362743284533;5607.411221821691;5610.298712660918;5636.718164091779;6192.473639740628;6203.255609326865;6221.875562488724;6229.439350458435;6239.095331176999;6077.7568897244055;6135.69321533926;6175.845603859894;6300.716467758962;6331.366863313632;5768;5815.563832712563;5831.9708401458565;5835.328937172195;5840.386759390288;6267.2792628848265;6282.560220784592;6299.118123462719;6305.684715764193;6320.55756097078;5449.964506743706;5785.739641716049;6011.6994150293185;6049.395060494004;6131.019036954066;6194.473469755031;6240.126382306481;6247.125402443661;6259.3427690091885;6260.280067792171;6122.785702500391;6178.69106544671;6182.598131121553;6184.381561843869;6190.969045154746;6045.486133678599;6093.26880774301;6100.023996160593;6223;6226.315105338409;4891.388576427947;4936.358273424432;4938.629602779806;4943.629227807929;4954.554090131834;4372.475302963601;4415.271480205794;4420.182266280748;4456.309272062836;4465.687401881837;5695.45893140151;5710.771569137271;5722.941255867678;5750.108733146285;5760.510356619734;6221.222347206599;6246.437820596168;6256.405641464054;6306.211723534559;6313.053042043638;5534.169874518774;5598.944010559923;5603.439656034446;5699.5725320600295;5731.398203188689;5385.623006389594;5386.703731294817;5392.676439413569;5393.083175860113;5421.105517589633;6217.906731399033;6248.125262463259;6258.780942666985;6270.7805226816845;6283.748650054038;6134.938650613437;6151.446369826737;6175.166352542349;6281.277653069773;6324.30432652417;6190.2571691397325;6291.496680265476;6291.716872740646;6292.465140463112;6293.748250070129;6123.601965872204;6148.569600127948;6149.108379284889;6153.4154255345675;6179.073139029181;6123.785667501616;6139.124180917401;6148.524354154881;6231.626102433868;6251.437370636664;5654.349749778664;6037.879242415039;6041.486473649789;6076.513878889767;6125.571210015256;6037.939620603738;6072.392760723892;6076.756929722762;6092.086187071797;6100;4680.672352935262;5780.9883270428345;5839.416058394127;5884.08796636522;5899.823005309848;6081.49907206002;6098.664573448503;6104.380937231305;6111.5110791137495;6141.1402403664415;5912.201852749824;5913.319968203539;5913.85215369602;5926.407359264039;5934.020886553671 08:55:36 INFO - PROCESS | 1663 | |1;ext.html;493.1813190104427;512.1753976098483;521.6009752539308;540.9675419474835;545.4599946053406;30728.924487642937;31030.913918012873;32268.354632907358;41462.792686036606;42816.35775463363;43363.78318108413;43683.563164368345;43742.90642733933;43820.90447738808;43888.780556097256;9405;9652.324337296392;9654.372465789736;9654.71035868925;9663.226941844636;8718.520481373522;8970.058143894896;9391.43651380916;11634.941825290904;11938.761224775499;11881.168318217728;11919.28484290941;11935.16453848231;11937.641870743892;11956.58151964677;8685.609147588311;11927.46326415309;11938.64184074479;11965.341906195155;11965.521379144824;11415.885841141648;11811.527538898434;11823.940880295544;12040.518379264819;12058.216215945935;40711;40893;40974.59025409738;41091.58908410907;41120.76637700874;3465.6880880720855;3657.689096426807;3855.4409610606585;3971.3050216212337;4201.096764195682;25393.238202853758;28216.29459263535;31941.680583194102;32510.674893251;32524.186895327806;3224.500202468621;3356.429407000816;3364.7718582717375;3365.697087262158;3394.4229480988292;490706.99999999645;494042;496168;498994;499782.0021799772;59839.102413463836;59973.70013149906;60187.39812601818;60222.09666855001;60257.79484410287;64725;64798.676006619666;64929.35070649233;64957.67521162364;64996.67501662461;16476.588085297724;16824.15879206035;16833.32666693343;16865.241064152142;16873.409430669868;11827.822582661267;12048.939755301168;13090.934545327404;13136.802947955788;13144;15766;16289.755653665205;16392.180390980404;16433.835661643232;16516.339346426008;5383.919241211385;6441.1626488556185;6485;6529.11856899322;6529.216494020653;913.7304495173939;1004.6785028790716;1035.4822588705647;1106.181425744943;1142.3203194099497;27411.588826167623;29433.852830736138;29726.702732972826;30437.847810761246;30509.23726906845;1066.7813098314828;1126.8478755368085;1134.5348407153028;1162.88944058424;1175.641429364035;12802;14685.971981961375;15731.68536629261;15929;15995.280212390238;651259;653805;656458;659100;660385;57241.42758572444;57518.42481575131;57570.424295757344;57664.42335576675;57820.8435831281;1311.8032295155613;1437.8490258522704;1443.3721331220952;1453.7165252775608;1456.8543145685346;30677;30833.229169270948;30997.535036974914;31515.684843151274;31839.522407164375;49419;49644.503554965435;50232.49767502278;50253.49746502635;50311.99376012386;452389;452668;454888;455204;455361;34918.825405872805;39541.60458395494;40747;41769.79115104405;41853.79073104615;48020.27969580529;48931.510684892695;49751;49785.50214497954;49885.752856178886;36578.26843463169;37180;38974;39080.804595976835;40029.79985100056;37706.43440348342;38765;38964.80517597394;39115.41326880156;39385.07611679561;38007.23985520219;38549.6145038546;38736.80631596824;38874;39002.2199556013;32430.83784581062;32669.673303266663;33289.50197241178;34163.975080747616;34481.96554103381;24848.751512484643;24850.627240591766;24973.500529989662;25389.937609982313;25686.743132568434 08:55:36 INFO - PROCESS | 1663 | |2;jquery.html;382.6823736298872;384.4022544942613;388.33982230208653;390.2486734968334;411.87849584372606;20174.293899713513;20375.59248815023;21293.893530532325;21328.786712132875;21374.89312553437;20394.796052039474;20437.38687839367;21317.68023479645;21626.459338516546;21638.675419868676;5888.528917686596;6093.42112499314;6150.569460137792;6153.630782153063;6155.261368635747;5781.90143872665;5978.760849566012;6115.388461153893;6121;6126.081087836816;6023.969880150593;6055.21282233309;6068.150458935753;6077.1491991121275;6083.908741368883;5829.504492118175;5954.166416701665;6031.366706495799;6045.6070355426755;6049.425304596079;5215.504527069922;5222.686638801646;5250.789968401259;5254.15933450646;5275.498827611371;9416.529173541296;12762.425690843935;24773.50452990948;26463.603045954325;26530.46939061208;1968.6456437841182;2076.688496725488;2140.0262880389387;2470.7899828514596;2691.461707658476;10053.899461005369;10116.595336186543;10366.274360794747;13386.665333366744;14432.134071955716;1863.5881261963486;1875.456117725858;1923.8268555829902;1949.0741897598668;1988.3438465306415;106718.46640766824;108166;109114;113355.43322283418;113656;11353.886461135284;11356.716082097848;11454.083673306115;11471;11610.361430121424;14099.083559568595;14209.50266740659;14224.359903804354;14231.359588818528;14249.85750142506;8248.175182481704;8388.958055209807;8403.621837017348;8516.787080323042;8524.104968978292;8387.412881098386;8407.95796021016;8435.156484351515;8554.700585479603;8559.657613695383;4334.13317336534;4457.442819647544;4477.283634618446;4507.977460112744;4520.18636645407;1720.6644704282794;1764.4706588023541;1835.1925152932934;1905.7998910114516;1916.2430839818194;872.8821609082693;873.4279047224078;977.8288799460066;1009.5911155981839;1019.2814066083394;19945.401637950883;20458.693119603216;20634;21106.894465527574;21771.455713607287;946.6497395963538;947.4031360243002;948.5399581203111;1002.8997100290059;1036.8341065429495;9299.860502092473;10748.838767418494;10810.29733067348;11163.274387164809;11266.54933802655;570012;573731;576591;586838;587714;128866;130497;130596;130800.69199307886;134486;1047.61238341814;1064.9148068154684;1112.7718817642365;1140.4126874659557;1142.874283828778;13534.323283835573;18529.629407412045;19254.903725481283;19476;19518.024098795286;51804.740976294874;52661.21008184957;52925.470745292056;53242.46757532375;53559.732201338746;68586;70445.89872164767;71013.64493177707;74288;76720.84918726335;5236.162214045735;5439.646422982493;7624.618769061635;7684.001079859806;8050.798730031796;8827.029026807175;11881.524739010689;12563.497460101493;12630.179038362478;12645.430955607204;10776.191785616267;10824.945875270574;10987.340759554452;11014.118870490502;11074.501647426046;10646.680599582025;10796.082333001468;10821.9458902705;10914.50884710174;10996.450177491253;11143.944280278547;11168.66494005181;11346.716332091433;11350.54597816078;11384.886151138713;6767.593944363354;7910.527566539824;7971.521708697497;8035.236652518001;8102.594870256581;10634.574617015232;11072.446377681244;11205.60780372683;11273.549058037586;11352.716182095513 08:55:36 INFO - PROCESS | 1663 | |3;mootools.html;1417.3834382043806;1575.8975666581675;1587.2063968015993;1825.717013862851;1968.41931630169;7799.064112306524;8552.315814734822;8762.255208307302;8764.386492945505;8897.955510222457;8485.703000394988;8748.606312715927;8748.78128046799;8749.825003499926;8759.299256059516;2093.916243350272;2248.449129963153;2331.9533609327887;2341.1337805012126;2369.7156341239074;2358.1157066100213;2377.465070359164;2387.116766796279;2393.0069021356117;2394.8084153267782;2299.7700229976954;2316.548273086749;2364.562555927159;2365.6215005599106;2366.183666635008;2061.515543847202;2263.3323169664986;2292.6675632033343;2341.391238278043;2341.660459233419;2345.319857241398;2367.8105751540006;2370.2178281167176;2371.513839662865;2372.905083796646;8642.524661143634;8687.826243475158;8706.956465217696;8719.869201961976;8720.040795512488;5129.435762066169;5284.445133261029;5287.550558202557;5290.867728306823;5295.973520132413;2969.495185818416;3257.462518684414;3363.3105213431186;3489.1974845785358;3517.5251341068883;1042.5881776698238;1043.118564812732;1051.8211903976342;1056.9365838049741;1065.63235683689;11501;11526;11529.711757206052;11551.884481155248;11556.653300401002;4607.976960115245;4651.767411629473;4750.263709125091;4761.4524329701835;4787.473377928425;9833.704988850346;9843.114119729116;9855.063768941938;9857.950710246547;10018.198544116452;5323.1749078892835;5326.414094449608;5332.813351532755;5337.572994160458;5337.86655333612;4380.233459144701;4535.591796738309;4703.2004559225015;4855.732934688555;4856.198727210041;3583.874564390286;3740.653364788674;3865.7487263328358;3910.921781564353;4009.4386785850006;1275.2476039136768;1288.9935550322189;1295.086963690596;1297.9675508112373;1302.6222395505197;941.9623215071457;957.9185769209695;965.8358079126565;966.3718582921158;1000.5947591225565;5792.652440853562;5934.109883517507;5984.251968503937;6042.305134909454;6101.572889897665;440.0560797090287;451.17435093778306;454.1983399300238;455.29202090749203;455.8313424033129;3805.6765174959883;4054.189162167578;4076;4116.4648595682365;4136.358864376027;10297.433641149808;10699.304545204537;10724.946375268073;10727.195460340507;10755.924407559181;10226.28416010872;10652.307600005975;10657.786844263072;10682.946585267024;10703.892961070445;8048.396370272136;8090;8120.025596928406;8192.672293108328;8232.794180145425;5886.087656413263;5889.970550147394;5942.405759424023;5958.851028724316;5976.073708575178;9514;9943;9955.601775929172;9960.452175130438;9971.202303815533;9560.76098097553;10056.396616203052;10062.245331600014;10062.748431288983;10067.993200679874;7237.927620723725;7454.4781865268915;7520.8495830082;7530.510516816389;7630.923690763243;6890.93109068923;8343.332533397192;8421.789455263668;8443.113473085483;8451.112633173674;7203.639818009184;7329.340359367682;7420.183779784327;7564.546127232384;7574.166841647525;7168.103987001625;7302.780916572511;7338.082739657543;7439.51643143194;7464.813379665552;6914.619695916773;6968.7212511501;7008.649567521502;7011.1586609607175;7029.3673569379;4897.436794768647;5107.10625640504;5139.897202055863;5259.658122222043;5264.420913699567;5179.533841954242;5238.292416449309;5240.9518096381635;5280.524752772269;5400.081986062457 08:55:36 INFO - PROCESS | 1663 | |4;prototype.html;284.85062771716116;309.5140629212136;316.6216371436133;318.40935065453584;324.438721012648;4797.2490041135;5317.803494213809;5321.388040375353;5332.653377530468;5349.090654588719;5008.799648014085;5288.418273989858;5293.9735301323435;5297.841064768054;5317.288183026655;3733.88798336049;3822.216445628653;3894.1822217334393;3988.262171498282;3999.2401443725744;3043.360894212218;3988.501437320335;3990.74060186087;4016.1967606478674;4029.7783621900785;3475.5308033415545;3955.545112312092;3962.286788378091;3967.246223217594;3969.563348031714;3187.3306605612847;3277.5411442398085;3396.8811091611674;3956.327424337855;3998.8400463981407;2950.395168990352;3442.6729460701445;3447;3475.1138459692615;3485.3900567400856;4161.4382058422025;4996.000799840047;5027.321311622921;5040.621953353477;5045.4197767256865;4164.042270277822;4323.3947247385395;4442.133783912139;4644.280136578802;4672.509386514435;5529.3364796224305;5534.557235421156;5535.924028489317;5564.415736347706;5596.2165296858475;3875.70163995062;3895.2404281165186;3902.356111241642;3911.902202444933;3911.9217615647526;4882.609391248691;5223.817166399158;5487.847552014082;5518.558515318765;5858.916100521417;3345;3571.482105115887;3912.6087391260644;3933.3116704576582;3962.227365663668;3304.7521435892404;3988.3020471417385;3999.2201520703766;4030.556638769733;4045.7167998239843;3968.2341358095946;4593.083392046075;4593.655475839326;4611.930821037688;4664.65015123867;4439.800208990603;5044;5286.7092309922555;5567.860803479864;5588.608797384144;1202.7173614200656;1356.6337088985917;1365.4774925957486;1365.9931700341435;1370.1984339161502;709.9183593886665;835.3651225068992;839.4082172068677;847.631280393031;855.5038077914794;4025.852631999866;4963.875903102451;5069.0737519743;5107.361579802525;5203.401608815035;5671.376148623648;5686.3744988051285;5690.630109042899;5706.4864162225595;5717.428257174332;4312.396264522969;4880.975595122001;5112.699285321546;5138.640295179302;5232.279214808278;2792.8184667996516;2847.71852666301;2970.450466663674;2972.524396096614;2989.936943508972;5940.732667029994;5948.910766338508;5948.910766338508;5952.464278214981;5997;5248.373004368658;5270.868228294324;5564.554835613222;5635.126555383922;5891.852703682443;4564.224081906151;4682.812687492462;5634.211210430543;5647.576431767552;6010.519158467312;4843.515648435128;4940.246212594478;5055.963527476932;5679.346875109415;5766.682832444256;5302;5669.433056694297;5676.105186354906;5690.032694442038;5708.914366284592;5000.574951129195;5332.440093790251;6000.489958353589;6019.652335589211;6024.638521688713;3255.674432556725;3595.676389124991;3790.195255510847;3843.1545060086737;3853.133045064838;5221.712805795718;5696.401877802771;5704;5740;5774.2782152230975;5328.880935003693;5522.570069169553;5566.24855644499;5577.972110139423;5582.441755824385;4826.91394436249;5336.412994570516;5558.6381336572795;5562.276904002454;5591.832245032656;4767.951050768826;4975.57707594844;5223.608229382888;5418.566514678895;5442.564594832483;4934.309196712391;5290.650884024549;5344.839654810206;5352.625316227983;5486.862828429162;4609.677322587387;5082.923756143735;5347.251384806208;5510.118381059011;5510.311211098613 08:55:36 INFO - PROCESS | 1663 | |5;yui.html;697.1599222936362;774.6436639145993;825.3685930263349;838.0446291228005;838.291643561191;14460.493882714094;15113.622159446018;15122.697546049072;15124.621884452867;15129.940904136729;13729.038967272294;13827.65430864229;13858.93070534646;13859.267591551055;13877.028607997445;617.9999999999989;741.280957471252;887.1394747095339;918.5269586163131;925.4909799610222;905.6377449020412;917.954102294886;919.8666193401953;921.8571121476182;925.1257561604286;978.0122076702546;982.1602529836994;982.4841957972051;987.0918754745651;987.239825334492;667.0561155964305;933.6078846884291;963.4219468319022;963.5134257200069;965.8792650918635;727.432602569991;727.7671145233527;728.3735987051132;728.6611725547646;729.2452311857203;14370.065945713495;14425.927870360581;14437;14454.638634034129;14511.274436278143;13456.259905705183;13529.932350338284;13531.458741650322;13560.186388816703;13704.65738356539;12180.756384872342;12203;12262.81605775914;12398.132130750852;12727.74544509114;4472.217361961676;4485;4620.237660785966;4657.860264192046;4667.789949452283;83198.58400707958;85068.57465712632;85402.14597854066;85945;86066;10728.731781705364;10735.463226838627;10823.675289741162;10848.18638602108;10902.236843420884;19406.417807465798;20022.099005544784;20075.899620501805;20609;20637.690434643493;7036.859262814715;7536.472446928662;8008.119106898236;8039.557824319601;8061.153578874134;4401.382153490752;4461.800300695136;4537.228671125917;4558.339040839126;4585.358049873021;3337.983310083434;3417.863285468603;3442.5868895732674;3444.8622055118017;3449.448088305859;580.692233116449;595.8152972578514;602.1750202222983;609.6372658268323;609.6494515653428;416.86451903131604;657.9013148027834;685.6871465817104;700.4396482813729;729.3363039633907;8521.914780852236;9023.81952360949;9088.136627020422;9252.444853308823;9327.207187389013;645.6610279603245;657.3032585459429;670.5172275961397;677.4174210179242;682.2427105912533;4296.82812687489;4378.652821859362;4379.146066516999;4381.802818873159;4509.864704058883;88563.55718221496;89772.55113724389;90965;91349.08650913405;91524.54237728902;78733;80512;80698;81965.59017204876;82982.75525867117;73030.63484682543;73167;73821;74344;75117.62441187869;8558.621238023337;8599.484030958163;8627.6980305689;8628.352873534635;8900;45665.543344566126;45776.771116144206;45858;46473;46872.82817929579;57398.713006436374;57476.71261643832;63654;64165;64208.67895660492;9828.655997040069;11341;11383.943080284545;11466.885331146581;12132.636020919386;11978.64064078079;12367.814482782946;12444.75110497767;12628.747425051632;12972.09195356316;11821.586244481401;11831.40842957866;11834.408279585814;11911.642650720492;12120.212186207867;10346.793064138825;11686.18196726221;11839.230450020721;12111.636650900487;12360.320182389694;10051.145652619609;11130.833037504282;11444.599439019927;11715.824262636237;11851.940740296588;9105;9366.95316523413;9380.718578442658;9509;9590.184834288884;8127.2279133482225;9223.262139028728;9801.362911410735;9818.950905245429;9824.607015719292 08:55:36 INFO - PROCESS | 1663 | __end_tp_report 08:55:36 INFO - PROCESS | 1663 | __start_cc_report 08:55:36 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1133 08:55:36 INFO - PROCESS | 1663 | __end_cc_report 08:55:36 INFO - PROCESS | 1663 | __startTimestamp1464623736476__endTimestamp 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | ------- Summary: start ------- 08:55:36 INFO - PROCESS | 1663 | Number of tests: 6 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5701.38 Median:5899.82 stddev:634.35 (10.8%) stddev-sans-first:607.85 08:55:36 INFO - PROCESS | 1663 | Values: 3307.0 3415.6 3423.6 3431.4 3484.2 5540.7 5594.3 5619.1 5657.5 5677.7 5609.4 5665.4 5666.3 5671.4 5681.1 5078.2 5112.7 5136.8 5141.1 5197.8 5162.8 5178.8 5183.5 5193.2 5210.2 5103.7 5131.8 5137.4 5137.9 5192.0 5188.5 5220.0 5229.3 5233.5 5265.3 4985.2 5004.2 5051.4 5106.2 5115.2 5524.2 5541.4 5607.4 5610.3 5636.7 6192.5 6203.3 6221.9 6229.4 6239.1 6077.8 6135.7 6175.8 6300.7 6331.4 5768.0 5815.6 5832.0 5835.3 5840.4 6267.3 6282.6 6299.1 6305.7 6320.6 5450.0 5785.7 6011.7 6049.4 6131.0 6194.5 6240.1 6247.1 6259.3 6260.3 6122.8 6178.7 6182.6 6184.4 6191.0 6045.5 6093.3 6100.0 6223.0 6226.3 4891.4 4936.4 4938.6 4943.6 4954.6 4372.5 4415.3 4420.2 4456.3 4465.7 5695.5 5710.8 5722.9 5750.1 5760.5 6221.2 6246.4 6256.4 6306.2 6313.1 5534.2 5598.9 5603.4 5699.6 5731.4 5385.6 5386.7 5392.7 5393.1 5421.1 6217.9 6248.1 6258.8 6270.8 6283.7 6134.9 6151.4 6175.2 6281.3 6324.3 6190.3 6291.5 6291.7 6292.5 6293.7 6123.6 6148.6 6149.1 6153.4 6179.1 6123.8 6139.1 6148.5 6231.6 6251.4 5654.3 6037.9 6041.5 6076.5 6125.6 6037.9 6072.4 6076.8 6092.1 6100.0 4680.7 5781.0 5839.4 5884.1 5899.8 6081.5 6098.7 6104.4 6111.5 6141.1 5912.2 5913.3 5913.9 5926.4 5934.0 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:67953.90 Median:21202.53 stddev:144709.08 (682.5%) stddev-sans-first:145026.39 08:55:36 INFO - PROCESS | 1663 | Values: 493.2 512.2 521.6 541.0 545.5 30728.9 31030.9 32268.4 41462.8 42816.4 43363.8 43683.6 43742.9 43820.9 43888.8 9405.0 9652.3 9654.4 9654.7 9663.2 8718.5 8970.1 9391.4 11634.9 11938.8 11881.2 11919.3 11935.2 11937.6 11956.6 8685.6 11927.5 11938.6 11965.3 11965.5 11415.9 11811.5 11823.9 12040.5 12058.2 40711.0 40893.0 40974.6 41091.6 41120.8 3465.7 3657.7 3855.4 3971.3 4201.1 25393.2 28216.3 31941.7 32510.7 32524.2 3224.5 3356.4 3364.8 3365.7 3394.4 490707.0 494042.0 496168.0 498994.0 499782.0 59839.1 59973.7 60187.4 60222.1 60257.8 64725.0 64798.7 64929.4 64957.7 64996.7 16476.6 16824.2 16833.3 16865.2 16873.4 11827.8 12048.9 13090.9 13136.8 13144.0 15766.0 16289.8 16392.2 16433.8 16516.3 5383.9 6441.2 6485.0 6529.1 6529.2 913.7 1004.7 1035.5 1106.2 1142.3 27411.6 29433.9 29726.7 30437.8 30509.2 1066.8 1126.8 1134.5 1162.9 1175.6 12802.0 14686.0 15731.7 15929.0 15995.3 651259.0 653805.0 656458.0 659100.0 660385.0 57241.4 57518.4 57570.4 57664.4 57820.8 1311.8 1437.8 1443.4 1453.7 1456.9 30677.0 30833.2 30997.5 31515.7 31839.5 49419.0 49644.5 50232.5 50253.5 50312.0 452389.0 452668.0 454888.0 455204.0 455361.0 34918.8 39541.6 40747.0 41769.8 41853.8 48020.3 48931.5 49751.0 49785.5 49885.8 36578.3 37180.0 38974.0 39080.8 40029.8 37706.4 38765.0 38964.8 39115.4 39385.1 38007.2 38549.6 38736.8 38874.0 39002.2 32430.8 32669.7 33289.5 34164.0 34482.0 24848.8 24850.6 24973.5 25389.9 25686.7 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34019.48 Median:21351.84 stddev:96693.84 (452.9%) stddev-sans-first:96932.11 08:55:36 INFO - PROCESS | 1663 | Values: 382.7 384.4 388.3 390.2 411.9 20174.3 20375.6 21293.9 21328.8 21374.9 20394.8 20437.4 21317.7 21626.5 21638.7 5888.5 6093.4 6150.6 6153.6 6155.3 5781.9 5978.8 6115.4 6121.0 6126.1 6024.0 6055.2 6068.2 6077.1 6083.9 5829.5 5954.2 6031.4 6045.6 6049.4 5215.5 5222.7 5250.8 5254.2 5275.5 9416.5 12762.4 24773.5 26463.6 26530.5 1968.6 2076.7 2140.0 2470.8 2691.5 10053.9 10116.6 10366.3 13386.7 14432.1 1863.6 1875.5 1923.8 1949.1 1988.3 106718.5 108166.0 109114.0 113355.4 113656.0 11353.9 11356.7 11454.1 11471.0 11610.4 14099.1 14209.5 14224.4 14231.4 14249.9 8248.2 8389.0 8403.6 8516.8 8524.1 8387.4 8408.0 8435.2 8554.7 8559.7 4334.1 4457.4 4477.3 4508.0 4520.2 1720.7 1764.5 1835.2 1905.8 1916.2 872.9 873.4 977.8 1009.6 1019.3 19945.4 20458.7 20634.0 21106.9 21771.5 946.6 947.4 948.5 1002.9 1036.8 9299.9 10748.8 10810.3 11163.3 11266.5 570012.0 573731.0 576591.0 586838.0 587714.0 128866.0 130497.0 130596.0 130800.7 134486.0 1047.6 1064.9 1112.8 1140.4 1142.9 13534.3 18529.6 19254.9 19476.0 19518.0 51804.7 52661.2 52925.5 53242.5 53559.7 68586.0 70445.9 71013.6 74288.0 76720.8 5236.2 5439.6 7624.6 7684.0 8050.8 8827.0 11881.5 12563.5 12630.2 12645.4 10776.2 10824.9 10987.3 11014.1 11074.5 10646.7 10796.1 10821.9 10914.5 10996.5 11143.9 11168.7 11346.7 11350.5 11384.9 6767.6 7910.5 7971.5 8035.2 8102.6 10634.6 11072.4 11205.6 11273.5 11352.7 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5677.87 Median:4876.82 stddev:3187.28 (65.4%) stddev-sans-first:3180.14 08:55:36 INFO - PROCESS | 1663 | Values: 1417.4 1575.9 1587.2 1825.7 1968.4 7799.1 8552.3 8762.3 8764.4 8898.0 8485.7 8748.6 8748.8 8749.8 8759.3 2093.9 2248.4 2332.0 2341.1 2369.7 2358.1 2377.5 2387.1 2393.0 2394.8 2299.8 2316.5 2364.6 2365.6 2366.2 2061.5 2263.3 2292.7 2341.4 2341.7 2345.3 2367.8 2370.2 2371.5 2372.9 8642.5 8687.8 8707.0 8719.9 8720.0 5129.4 5284.4 5287.6 5290.9 5296.0 2969.5 3257.5 3363.3 3489.2 3517.5 1042.6 1043.1 1051.8 1056.9 1065.6 11501.0 11526.0 11529.7 11551.9 11556.7 4608.0 4651.8 4750.3 4761.5 4787.5 9833.7 9843.1 9855.1 9858.0 10018.2 5323.2 5326.4 5332.8 5337.6 5337.9 4380.2 4535.6 4703.2 4855.7 4856.2 3583.9 3740.7 3865.7 3910.9 4009.4 1275.2 1289.0 1295.1 1298.0 1302.6 942.0 957.9 965.8 966.4 1000.6 5792.7 5934.1 5984.3 6042.3 6101.6 440.1 451.2 454.2 455.3 455.8 3805.7 4054.2 4076.0 4116.5 4136.4 10297.4 10699.3 10724.9 10727.2 10755.9 10226.3 10652.3 10657.8 10682.9 10703.9 8048.4 8090.0 8120.0 8192.7 8232.8 5886.1 5890.0 5942.4 5958.9 5976.1 9514.0 9943.0 9955.6 9960.5 9971.2 9560.8 10056.4 10062.2 10062.7 10068.0 7237.9 7454.5 7520.8 7530.5 7630.9 6890.9 8343.3 8421.8 8443.1 8451.1 7203.6 7329.3 7420.2 7564.5 7574.2 7168.1 7302.8 7338.1 7439.5 7464.8 6914.6 6968.7 7008.6 7011.2 7029.4 4897.4 5107.1 5139.9 5259.7 5264.4 5179.5 5238.3 5241.0 5280.5 5400.1 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4456.92 Median:5033.97 stddev:1383.60 (27.5%) stddev-sans-first:1351.58 08:55:36 INFO - PROCESS | 1663 | Values: 284.9 309.5 316.6 318.4 324.4 4797.2 5317.8 5321.4 5332.7 5349.1 5008.8 5288.4 5294.0 5297.8 5317.3 3733.9 3822.2 3894.2 3988.3 3999.2 3043.4 3988.5 3990.7 4016.2 4029.8 3475.5 3955.5 3962.3 3967.2 3969.6 3187.3 3277.5 3396.9 3956.3 3998.8 2950.4 3442.7 3447.0 3475.1 3485.4 4161.4 4996.0 5027.3 5040.6 5045.4 4164.0 4323.4 4442.1 4644.3 4672.5 5529.3 5534.6 5535.9 5564.4 5596.2 3875.7 3895.2 3902.4 3911.9 3911.9 4882.6 5223.8 5487.8 5518.6 5858.9 3345.0 3571.5 3912.6 3933.3 3962.2 3304.8 3988.3 3999.2 4030.6 4045.7 3968.2 4593.1 4593.7 4611.9 4664.7 4439.8 5044.0 5286.7 5567.9 5588.6 1202.7 1356.6 1365.5 1366.0 1370.2 709.9 835.4 839.4 847.6 855.5 4025.9 4963.9 5069.1 5107.4 5203.4 5671.4 5686.4 5690.6 5706.5 5717.4 4312.4 4881.0 5112.7 5138.6 5232.3 2792.8 2847.7 2970.5 2972.5 2989.9 5940.7 5948.9 5948.9 5952.5 5997.0 5248.4 5270.9 5564.6 5635.1 5891.9 4564.2 4682.8 5634.2 5647.6 6010.5 4843.5 4940.2 5056.0 5679.3 5766.7 5302.0 5669.4 5676.1 5690.0 5708.9 5000.6 5332.4 6000.5 6019.7 6024.6 3255.7 3595.7 3790.2 3843.2 3853.1 5221.7 5696.4 5704.0 5740.0 5774.3 5328.9 5522.6 5566.2 5578.0 5582.4 4826.9 5336.4 5558.6 5562.3 5591.8 4768.0 4975.6 5223.6 5418.6 5442.6 4934.3 5290.7 5344.8 5352.6 5486.9 4609.7 5082.9 5347.3 5510.1 5510.3 08:55:36 INFO - PROCESS | 1663 | 08:55:36 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18464.98 Median:609.64 stddev:25742.74 (4222.6%) stddev-sans-first:25780.38 08:55:36 INFO - PROCESS | 1663 | Values: 697.2 774.6 825.4 838.0 838.3 14460.5 15113.6 15122.7 15124.6 15129.9 13729.0 13827.7 13858.9 13859.3 13877.0 618.0 741.3 887.1 918.5 925.5 905.6 918.0 919.9 921.9 925.1 978.0 982.2 982.5 987.1 987.2 667.1 933.6 963.4 963.5 965.9 727.4 727.8 728.4 728.7 729.2 14370.1 14425.9 14437.0 14454.6 14511.3 13456.3 13529.9 13531.5 13560.2 13704.7 12180.8 12203.0 12262.8 12398.1 12727.7 4472.2 4485.0 4620.2 4657.9 4667.8 83198.6 85068.6 85402.1 85945.0 86066.0 10728.7 10735.5 10823.7 10848.2 10902.2 19406.4 20022.1 20075.9 20609.0 20637.7 7036.9 7536.5 8008.1 8039.6 8061.2 4401.4 4461.8 4537.2 4558.3 4585.4 3338.0 3417.9 3442.6 3444.9 3449.4 580.7 595.8 602.2 609.6 609.6 416.9 657.9 685.7 700.4 729.3 8521.9 9023.8 9088.1 9252.4 9327.2 645.7 657.3 670.5 677.4 682.2 4296.8 4378.7 4379.1 4381.8 4509.9 88563.6 89772.6 90965.0 91349.1 91524.5 78733.0 80512.0 80698.0 81965.6 82982.8 73030.6 73167.0 73821.0 74344.0 75117.6 8558.6 8599.5 8627.7 8628.4 8900.0 45665.5 45776.8 45858.0 46473.0 46872.8 57398.7 57476.7 63654.0 64165.0 64208.7 9828.7 11341.0 11383.9 11466.9 12132.6 11978.6 12367.8 12444.8 12628.7 12972.1 11821.6 11831.4 11834.4 11911.6 12120.2 10346.8 11686.2 11839.2 12111.6 12360.3 10051.1 11130.8 11444.6 11715.8 11851.9 9105.0 9367.0 9380.7 9509.0 9590.2 8127.2 9223.3 9801.4 9819.0 9824.6 08:55:36 INFO - PROCESS | 1663 | -------- Summary: end -------- 08:55:36 INFO - PROCESS | 1663 | 08:55:37 INFO - TEST-INFO | 1663: exit 0 08:55:37 INFO - TEST-OK | dromaeo_css | took 1116152ms 08:55:37 INFO - TEST-START | kraken 08:55:37 INFO - operating with platform_type : mac_ 08:55:37 INFO - Initialising browser for kraken test... 08:55:37 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpW2i4vi/profile http://localhost:49277/getInfo.html) 08:55:39 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 08:55:39 INFO - PROCESS | 1673 | colorDepth:24 08:55:39 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 08:55:39 INFO - PROCESS | 1673 | __metrics 08:55:39 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:55:39 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:55:40 INFO - TEST-INFO | 1673: exit 0 08:55:40 INFO - Browser initialized. 08:55:40 INFO - Running cycle 1/1 for kraken test... 08:55:40 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpW2i4vi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:52 INFO - PROCESS | 1674 | RSS: Main: 241672192 08:55:52 INFO - PROCESS | 1674 | 08:55:54 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:55:55 INFO - PROCESS | 1674 | RSS: Main: 292089856 08:55:55 INFO - PROCESS | 1674 | 08:55:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:55:59 INFO - PROCESS | 1674 | RSS: Main: 301465600 08:55:59 INFO - PROCESS | 1674 | 08:56:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:56:03 INFO - PROCESS | 1674 | RSS: Main: 387129344 08:56:03 INFO - PROCESS | 1674 | 08:56:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:56:05 INFO - PROCESS | 1674 | RSS: Main: 287014912 08:56:05 INFO - PROCESS | 1674 | 08:56:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:56:08 INFO - PROCESS | 1674 | RSS: Main: 290816000 08:56:08 INFO - PROCESS | 1674 | 08:56:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:56:12 INFO - PROCESS | 1674 | RSS: Main: 330207232 08:56:12 INFO - PROCESS | 1674 | 08:56:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:56:15 INFO - PROCESS | 1674 | RSS: Main: 329629696 08:56:15 INFO - PROCESS | 1674 | 08:56:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:56:18 INFO - PROCESS | 1674 | RSS: Main: 329134080 08:56:18 INFO - PROCESS | 1674 | 08:56:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:56:21 INFO - PROCESS | 1674 | RSS: Main: 309428224 08:56:21 INFO - PROCESS | 1674 | 08:56:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:56:23 INFO - PROCESS | 1674 | RSS: Main: 316911616 08:56:23 INFO - PROCESS | 1674 | 08:56:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:56:26 INFO - PROCESS | 1674 | RSS: Main: 320389120 08:56:26 INFO - PROCESS | 1674 | 08:56:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:56:30 INFO - PROCESS | 1674 | RSS: Main: 323530752 08:56:30 INFO - PROCESS | 1674 | 08:56:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:56:33 INFO - PROCESS | 1674 | RSS: Main: 322932736 08:56:33 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:56:35 INFO - PROCESS | 1674 | RSS: Main: 366891008 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | __start_tp_report 08:56:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 08:56:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 08:56:35 INFO - PROCESS | 1674 | |i|pagename|runs| 08:56:35 INFO - PROCESS | 1674 | |0;ai-astar;112;109;111;112;111;112;112;112;111;113 08:56:35 INFO - PROCESS | 1674 | |1;audio-beat-detection;177;148;142;169;131;131;158;144;180;143 08:56:35 INFO - PROCESS | 1674 | |2;audio-dft;186;184;183;184;183;184;188;185;184;182 08:56:35 INFO - PROCESS | 1674 | |3;audio-fft;78;72;57;86;62;58;61;67;83;58 08:56:35 INFO - PROCESS | 1674 | |4;audio-oscillator;87;94;92;73;70;69;96;71;68;103 08:56:35 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;87;83;86;84;86;84;87;84;86;84 08:56:35 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;92;92;93;92;92;93;93;92;93 08:56:35 INFO - PROCESS | 1674 | |7;imaging-desaturate;85;85;85;83;84;86;86;89;84;87 08:56:35 INFO - PROCESS | 1674 | |8;json-parse-financial;54;53;55;54;53;54;54;55;55;53 08:56:35 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;63;71;78;72;73;70;69;69;70;71 08:56:35 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;87;86;86;86;96;106;106;108;144;87 08:56:35 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;139;140;139;139;137;136;137;134;157;140 08:56:35 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;169;225;198;162;167;165;163;164;164;162 08:56:35 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;65;65;66;65;66 08:56:35 INFO - PROCESS | 1674 | __end_tp_report 08:56:35 INFO - PROCESS | 1674 | __start_cc_report 08:56:35 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1931 08:56:35 INFO - PROCESS | 1674 | __end_cc_report 08:56:35 INFO - PROCESS | 1674 | __startTimestamp1464623795589__endTimestamp 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | ------- Summary: start ------- 08:56:35 INFO - PROCESS | 1674 | Number of tests: 14 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.08 (1.0%) stddev-sans-first:1.13 08:56:35 INFO - PROCESS | 1674 | Values: 112.0 109.0 111.0 112.0 111.0 112.0 112.0 112.0 111.0 113.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:152.30 Median:153.00 stddev:17.88 (11.7%) stddev-sans-first:16.58 08:56:35 INFO - PROCESS | 1674 | Values: 177.0 148.0 142.0 169.0 131.0 131.0 158.0 144.0 180.0 143.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.00 stddev:1.70 (0.9%) stddev-sans-first:1.69 08:56:35 INFO - PROCESS | 1674 | Values: 186.0 184.0 183.0 184.0 183.0 184.0 188.0 185.0 184.0 182.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:68.20 Median:69.50 stddev:10.91 (15.7%) stddev-sans-first:10.98 08:56:35 INFO - PROCESS | 1674 | Values: 78.0 72.0 57.0 86.0 62.0 58.0 61.0 67.0 83.0 58.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:80.00 stddev:13.40 (16.8%) stddev-sans-first:14.10 08:56:35 INFO - PROCESS | 1674 | Values: 87.0 94.0 92.0 73.0 70.0 69.0 96.0 71.0 68.0 103.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.45 (1.7%) stddev-sans-first:1.36 08:56:35 INFO - PROCESS | 1674 | Values: 87.0 83.0 86.0 84.0 86.0 84.0 87.0 84.0 86.0 84.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 08:56:35 INFO - PROCESS | 1674 | Values: 93.0 92.0 92.0 93.0 92.0 92.0 93.0 93.0 92.0 93.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.71 (2.0%) stddev-sans-first:1.81 08:56:35 INFO - PROCESS | 1674 | Values: 85.0 85.0 85.0 83.0 84.0 86.0 86.0 89.0 84.0 87.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 08:56:35 INFO - PROCESS | 1674 | Values: 54.0 53.0 55.0 54.0 53.0 54.0 54.0 55.0 55.0 53.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:3.75 (5.3%) stddev-sans-first:2.79 08:56:35 INFO - PROCESS | 1674 | Values: 63.0 71.0 78.0 72.0 73.0 70.0 69.0 69.0 70.0 71.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:99.20 Median:86.00 stddev:18.28 (21.3%) stddev-sans-first:18.85 08:56:35 INFO - PROCESS | 1674 | Values: 87.0 86.0 86.0 86.0 96.0 106.0 106.0 108.0 144.0 87.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:139.00 stddev:6.34 (4.6%) stddev-sans-first:6.72 08:56:35 INFO - PROCESS | 1674 | Values: 139.0 140.0 139.0 139.0 137.0 136.0 137.0 134.0 157.0 140.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:166.00 stddev:20.93 (12.6%) stddev-sans-first:22.12 08:56:35 INFO - PROCESS | 1674 | Values: 169.0 225.0 198.0 162.0 167.0 165.0 163.0 164.0 164.0 162.0 08:56:35 INFO - PROCESS | 1674 | 08:56:35 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 08:56:35 INFO - PROCESS | 1674 | Values: 67.0 67.0 67.0 67.0 67.0 65.0 65.0 66.0 65.0 66.0 08:56:35 INFO - PROCESS | 1674 | -------- Summary: end -------- 08:56:35 INFO - PROCESS | 1674 | 08:56:36 INFO - TEST-INFO | 1674: exit 0 08:56:36 INFO - TEST-OK | kraken | took 59384ms 08:56:36 INFO - SUITE-END | took 1175s 08:56:36 INFO - Completed test suite (00:19:35) 08:56:36 INFO - javascript benchmark 08:56:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3307.0244277938023, 3415.641357657446, 3423.5720534933134, 3431.4338134207856, 3484.1812174139072, 5540.722963851801, 5594.272744543204, 5619.129034999576, 5657.519110875575, 5677.659340439576, 5609.35492418371, 5665.433456654332, 5666.348369937458, 5671.432856714327, 5681.091025435931, 5078.212877004061, 5112.744362781865, 5136.845894623168, 5141.126008578532, 5197.766100525486, 5162.8193013244545, 5178.792848286064, 5183.53348198662, 5193.16909294513, 5210.218467229908, 5103.668261563005, 5131.820386286481, 5137.38351397831, 5137.897242055156, 5192.013517431676, 5188.455212202721, 5220.034293655686, 5229.346331708536, 5233.528982391565, 5265.262863199156, 4985.227289770091, 5004.199328107522, 5051.393832740095, 5106.157484015133, 5115.2071428928575, 5524.171374293888, 5541.362743284533, 5607.411221821691, 5610.298712660918, 5636.718164091779, 6192.473639740628, 6203.255609326865, 6221.875562488724, 6229.439350458435, 6239.095331176999, 6077.7568897244055, 6135.69321533926, 6175.845603859894, 6300.716467758962, 6331.366863313632, 5768.0, 5815.563832712563, 5831.9708401458565, 5835.328937172195, 5840.386759390288, 6267.2792628848265, 6282.560220784592, 6299.118123462719, 6305.684715764193, 6320.55756097078, 5449.964506743706, 5785.739641716049, 6011.6994150293185, 6049.395060494004, 6131.019036954066, 6194.473469755031, 6240.126382306481, 6247.125402443661, 6259.3427690091885, 6260.280067792171, 6122.785702500391, 6178.69106544671, 6182.598131121553, 6184.381561843869, 6190.969045154746, 6045.486133678599, 6093.26880774301, 6100.023996160593, 6223.0, 6226.315105338409, 4891.388576427947, 4936.358273424432, 4938.629602779806, 4943.629227807929, 4954.554090131834, 4372.475302963601, 4415.271480205794, 4420.182266280748, 4456.309272062836, 4465.687401881837, 5695.45893140151, 5710.771569137271, 5722.941255867678, 5750.108733146285, 5760.510356619734, 6221.222347206599, 6246.437820596168, 6256.405641464054, 6306.211723534559, 6313.053042043638, 5534.169874518774, 5598.944010559923, 5603.439656034446, 5699.5725320600295, 5731.398203188689, 5385.623006389594, 5386.703731294817, 5392.676439413569, 5393.083175860113, 5421.105517589633, 6217.906731399033, 6248.125262463259, 6258.780942666985, 6270.7805226816845, 6283.748650054038, 6134.938650613437, 6151.446369826737, 6175.166352542349, 6281.277653069773, 6324.30432652417, 6190.2571691397325, 6291.496680265476, 6291.716872740646, 6292.465140463112, 6293.748250070129, 6123.601965872204, 6148.569600127948, 6149.108379284889, 6153.4154255345675, 6179.073139029181, 6123.785667501616, 6139.124180917401, 6148.524354154881, 6231.626102433868, 6251.437370636664, 5654.349749778664, 6037.879242415039, 6041.486473649789, 6076.513878889767, 6125.571210015256, 6037.939620603738, 6072.392760723892, 6076.756929722762, 6092.086187071797, 6100.0, 4680.672352935262, 5780.9883270428345, 5839.416058394127, 5884.08796636522, 5899.823005309848, 6081.49907206002, 6098.664573448503, 6104.380937231305, 6111.5110791137495, 6141.1402403664415, 5912.201852749824, 5913.319968203539, 5913.85215369602, 5926.407359264039, 5934.020886553671], "value": 5661.323836334639, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.1813190104427, 512.1753976098483, 521.6009752539308, 540.9675419474835, 545.4599946053406, 30728.924487642937, 31030.913918012873, 32268.354632907358, 41462.792686036606, 42816.35775463363, 43363.78318108413, 43683.563164368345, 43742.90642733933, 43820.90447738808, 43888.780556097256, 9405.0, 9652.324337296392, 9654.372465789736, 9654.71035868925, 9663.226941844636, 8718.520481373522, 8970.058143894896, 9391.43651380916, 11634.941825290904, 11938.761224775499, 11881.168318217728, 11919.28484290941, 11935.16453848231, 11937.641870743892, 11956.58151964677, 8685.609147588311, 11927.46326415309, 11938.64184074479, 11965.341906195155, 11965.521379144824, 11415.885841141648, 11811.527538898434, 11823.940880295544, 12040.518379264819, 12058.216215945935, 40711.0, 40893.0, 40974.59025409738, 41091.58908410907, 41120.76637700874, 3465.6880880720855, 3657.689096426807, 3855.4409610606585, 3971.3050216212337, 4201.096764195682, 25393.238202853758, 28216.29459263535, 31941.680583194102, 32510.674893251, 32524.186895327806, 3224.500202468621, 3356.429407000816, 3364.7718582717375, 3365.697087262158, 3394.4229480988292, 490706.99999999645, 494042.0, 496168.0, 498994.0, 499782.0021799772, 59839.102413463836, 59973.70013149906, 60187.39812601818, 60222.09666855001, 60257.79484410287, 64725.0, 64798.676006619666, 64929.35070649233, 64957.67521162364, 64996.67501662461, 16476.588085297724, 16824.15879206035, 16833.32666693343, 16865.241064152142, 16873.409430669868, 11827.822582661267, 12048.939755301168, 13090.934545327404, 13136.802947955788, 13144.0, 15766.0, 16289.755653665205, 16392.180390980404, 16433.835661643232, 16516.339346426008, 5383.919241211385, 6441.1626488556185, 6485.0, 6529.11856899322, 6529.216494020653, 913.7304495173939, 1004.6785028790716, 1035.4822588705647, 1106.181425744943, 1142.3203194099497, 27411.588826167623, 29433.852830736138, 29726.702732972826, 30437.847810761246, 30509.23726906845, 1066.7813098314828, 1126.8478755368085, 1134.5348407153028, 1162.88944058424, 1175.641429364035, 12802.0, 14685.971981961375, 15731.68536629261, 15929.0, 15995.280212390238, 651259.0, 653805.0, 656458.0, 659100.0, 660385.0, 57241.42758572444, 57518.42481575131, 57570.424295757344, 57664.42335576675, 57820.8435831281, 1311.8032295155613, 1437.8490258522704, 1443.3721331220952, 1453.7165252775608, 1456.8543145685346, 30677.0, 30833.229169270948, 30997.535036974914, 31515.684843151274, 31839.522407164375, 49419.0, 49644.503554965435, 50232.49767502278, 50253.49746502635, 50311.99376012386, 452389.0, 452668.0, 454888.0, 455204.0, 455361.0, 34918.825405872805, 39541.60458395494, 40747.0, 41769.79115104405, 41853.79073104615, 48020.27969580529, 48931.510684892695, 49751.0, 49785.50214497954, 49885.752856178886, 36578.26843463169, 37180.0, 38974.0, 39080.804595976835, 40029.79985100056, 37706.43440348342, 38765.0, 38964.80517597394, 39115.41326880156, 39385.07611679561, 38007.23985520219, 38549.6145038546, 38736.80631596824, 38874.0, 39002.2199556013, 32430.83784581062, 32669.673303266663, 33289.50197241178, 34163.975080747616, 34481.96554103381, 24848.751512484643, 24850.627240591766, 24973.500529989662, 25389.937609982313, 25686.743132568434], "value": 20467.030147955535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.6823736298872, 384.4022544942613, 388.33982230208653, 390.2486734968334, 411.87849584372606, 20174.293899713513, 20375.59248815023, 21293.893530532325, 21328.786712132875, 21374.89312553437, 20394.796052039474, 20437.38687839367, 21317.68023479645, 21626.459338516546, 21638.675419868676, 5888.528917686596, 6093.42112499314, 6150.569460137792, 6153.630782153063, 6155.261368635747, 5781.90143872665, 5978.760849566012, 6115.388461153893, 6121.0, 6126.081087836816, 6023.969880150593, 6055.21282233309, 6068.150458935753, 6077.1491991121275, 6083.908741368883, 5829.504492118175, 5954.166416701665, 6031.366706495799, 6045.6070355426755, 6049.425304596079, 5215.504527069922, 5222.686638801646, 5250.789968401259, 5254.15933450646, 5275.498827611371, 9416.529173541296, 12762.425690843935, 24773.50452990948, 26463.603045954325, 26530.46939061208, 1968.6456437841182, 2076.688496725488, 2140.0262880389387, 2470.7899828514596, 2691.461707658476, 10053.899461005369, 10116.595336186543, 10366.274360794747, 13386.665333366744, 14432.134071955716, 1863.5881261963486, 1875.456117725858, 1923.8268555829902, 1949.0741897598668, 1988.3438465306415, 106718.46640766824, 108166.0, 109114.0, 113355.43322283418, 113656.0, 11353.886461135284, 11356.716082097848, 11454.083673306115, 11471.0, 11610.361430121424, 14099.083559568595, 14209.50266740659, 14224.359903804354, 14231.359588818528, 14249.85750142506, 8248.175182481704, 8388.958055209807, 8403.621837017348, 8516.787080323042, 8524.104968978292, 8387.412881098386, 8407.95796021016, 8435.156484351515, 8554.700585479603, 8559.657613695383, 4334.13317336534, 4457.442819647544, 4477.283634618446, 4507.977460112744, 4520.18636645407, 1720.6644704282794, 1764.4706588023541, 1835.1925152932934, 1905.7998910114516, 1916.2430839818194, 872.8821609082693, 873.4279047224078, 977.8288799460066, 1009.5911155981839, 1019.2814066083394, 19945.401637950883, 20458.693119603216, 20634.0, 21106.894465527574, 21771.455713607287, 946.6497395963538, 947.4031360243002, 948.5399581203111, 1002.8997100290059, 1036.8341065429495, 9299.860502092473, 10748.838767418494, 10810.29733067348, 11163.274387164809, 11266.54933802655, 570012.0, 573731.0, 576591.0, 586838.0, 587714.0, 128866.0, 130497.0, 130596.0, 130800.69199307886, 134486.0, 1047.61238341814, 1064.9148068154684, 1112.7718817642365, 1140.4126874659557, 1142.874283828778, 13534.323283835573, 18529.629407412045, 19254.903725481283, 19476.0, 19518.024098795286, 51804.740976294874, 52661.21008184957, 52925.470745292056, 53242.46757532375, 53559.732201338746, 68586.0, 70445.89872164767, 71013.64493177707, 74288.0, 76720.84918726335, 5236.162214045735, 5439.646422982493, 7624.618769061635, 7684.001079859806, 8050.798730031796, 8827.029026807175, 11881.524739010689, 12563.497460101493, 12630.179038362478, 12645.430955607204, 10776.191785616267, 10824.945875270574, 10987.340759554452, 11014.118870490502, 11074.501647426046, 10646.680599582025, 10796.082333001468, 10821.9458902705, 10914.50884710174, 10996.450177491253, 11143.944280278547, 11168.66494005181, 11346.716332091433, 11350.54597816078, 11384.886151138713, 6767.593944363354, 7910.527566539824, 7971.521708697497, 8035.236652518001, 8102.594870256581, 10634.574617015232, 11072.446377681244, 11205.60780372683, 11273.549058037586, 11352.716182095513], "value": 9467.35550646012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.3834382043806, 1575.8975666581675, 1587.2063968015993, 1825.717013862851, 1968.41931630169, 7799.064112306524, 8552.315814734822, 8762.255208307302, 8764.386492945505, 8897.955510222457, 8485.703000394988, 8748.606312715927, 8748.78128046799, 8749.825003499926, 8759.299256059516, 2093.916243350272, 2248.449129963153, 2331.9533609327887, 2341.1337805012126, 2369.7156341239074, 2358.1157066100213, 2377.465070359164, 2387.116766796279, 2393.0069021356117, 2394.8084153267782, 2299.7700229976954, 2316.548273086749, 2364.562555927159, 2365.6215005599106, 2366.183666635008, 2061.515543847202, 2263.3323169664986, 2292.6675632033343, 2341.391238278043, 2341.660459233419, 2345.319857241398, 2367.8105751540006, 2370.2178281167176, 2371.513839662865, 2372.905083796646, 8642.524661143634, 8687.826243475158, 8706.956465217696, 8719.869201961976, 8720.040795512488, 5129.435762066169, 5284.445133261029, 5287.550558202557, 5290.867728306823, 5295.973520132413, 2969.495185818416, 3257.462518684414, 3363.3105213431186, 3489.1974845785358, 3517.5251341068883, 1042.5881776698238, 1043.118564812732, 1051.8211903976342, 1056.9365838049741, 1065.63235683689, 11501.0, 11526.0, 11529.711757206052, 11551.884481155248, 11556.653300401002, 4607.976960115245, 4651.767411629473, 4750.263709125091, 4761.4524329701835, 4787.473377928425, 9833.704988850346, 9843.114119729116, 9855.063768941938, 9857.950710246547, 10018.198544116452, 5323.1749078892835, 5326.414094449608, 5332.813351532755, 5337.572994160458, 5337.86655333612, 4380.233459144701, 4535.591796738309, 4703.2004559225015, 4855.732934688555, 4856.198727210041, 3583.874564390286, 3740.653364788674, 3865.7487263328358, 3910.921781564353, 4009.4386785850006, 1275.2476039136768, 1288.9935550322189, 1295.086963690596, 1297.9675508112373, 1302.6222395505197, 941.9623215071457, 957.9185769209695, 965.8358079126565, 966.3718582921158, 1000.5947591225565, 5792.652440853562, 5934.109883517507, 5984.251968503937, 6042.305134909454, 6101.572889897665, 440.0560797090287, 451.17435093778306, 454.1983399300238, 455.29202090749203, 455.8313424033129, 3805.6765174959883, 4054.189162167578, 4076.0, 4116.4648595682365, 4136.358864376027, 10297.433641149808, 10699.304545204537, 10724.946375268073, 10727.195460340507, 10755.924407559181, 10226.28416010872, 10652.307600005975, 10657.786844263072, 10682.946585267024, 10703.892961070445, 8048.396370272136, 8090.0, 8120.025596928406, 8192.672293108328, 8232.794180145425, 5886.087656413263, 5889.970550147394, 5942.405759424023, 5958.851028724316, 5976.073708575178, 9514.0, 9943.0, 9955.601775929172, 9960.452175130438, 9971.202303815533, 9560.76098097553, 10056.396616203052, 10062.245331600014, 10062.748431288983, 10067.993200679874, 7237.927620723725, 7454.4781865268915, 7520.8495830082, 7530.510516816389, 7630.923690763243, 6890.93109068923, 8343.332533397192, 8421.789455263668, 8443.113473085483, 8451.112633173674, 7203.639818009184, 7329.340359367682, 7420.183779784327, 7564.546127232384, 7574.166841647525, 7168.103987001625, 7302.780916572511, 7338.082739657543, 7439.51643143194, 7464.813379665552, 6914.619695916773, 6968.7212511501, 7008.649567521502, 7011.1586609607175, 7029.3673569379, 4897.436794768647, 5107.10625640504, 5139.897202055863, 5259.658122222043, 5264.420913699567, 5179.533841954242, 5238.292416449309, 5240.9518096381635, 5280.524752772269, 5400.081986062457], "value": 4494.55188048541, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.85062771716116, 309.5140629212136, 316.6216371436133, 318.40935065453584, 324.438721012648, 4797.2490041135, 5317.803494213809, 5321.388040375353, 5332.653377530468, 5349.090654588719, 5008.799648014085, 5288.418273989858, 5293.9735301323435, 5297.841064768054, 5317.288183026655, 3733.88798336049, 3822.216445628653, 3894.1822217334393, 3988.262171498282, 3999.2401443725744, 3043.360894212218, 3988.501437320335, 3990.74060186087, 4016.1967606478674, 4029.7783621900785, 3475.5308033415545, 3955.545112312092, 3962.286788378091, 3967.246223217594, 3969.563348031714, 3187.3306605612847, 3277.5411442398085, 3396.8811091611674, 3956.327424337855, 3998.8400463981407, 2950.395168990352, 3442.6729460701445, 3447.0, 3475.1138459692615, 3485.3900567400856, 4161.4382058422025, 4996.000799840047, 5027.321311622921, 5040.621953353477, 5045.4197767256865, 4164.042270277822, 4323.3947247385395, 4442.133783912139, 4644.280136578802, 4672.509386514435, 5529.3364796224305, 5534.557235421156, 5535.924028489317, 5564.415736347706, 5596.2165296858475, 3875.70163995062, 3895.2404281165186, 3902.356111241642, 3911.902202444933, 3911.9217615647526, 4882.609391248691, 5223.817166399158, 5487.847552014082, 5518.558515318765, 5858.916100521417, 3345.0, 3571.482105115887, 3912.6087391260644, 3933.3116704576582, 3962.227365663668, 3304.7521435892404, 3988.3020471417385, 3999.2201520703766, 4030.556638769733, 4045.7167998239843, 3968.2341358095946, 4593.083392046075, 4593.655475839326, 4611.930821037688, 4664.65015123867, 4439.800208990603, 5044.0, 5286.7092309922555, 5567.860803479864, 5588.608797384144, 1202.7173614200656, 1356.6337088985917, 1365.4774925957486, 1365.9931700341435, 1370.1984339161502, 709.9183593886665, 835.3651225068992, 839.4082172068677, 847.631280393031, 855.5038077914794, 4025.852631999866, 4963.875903102451, 5069.0737519743, 5107.361579802525, 5203.401608815035, 5671.376148623648, 5686.3744988051285, 5690.630109042899, 5706.4864162225595, 5717.428257174332, 4312.396264522969, 4880.975595122001, 5112.699285321546, 5138.640295179302, 5232.279214808278, 2792.8184667996516, 2847.71852666301, 2970.450466663674, 2972.524396096614, 2989.936943508972, 5940.732667029994, 5948.910766338508, 5948.910766338508, 5952.464278214981, 5997.0, 5248.373004368658, 5270.868228294324, 5564.554835613222, 5635.126555383922, 5891.852703682443, 4564.224081906151, 4682.812687492462, 5634.211210430543, 5647.576431767552, 6010.519158467312, 4843.515648435128, 4940.246212594478, 5055.963527476932, 5679.346875109415, 5766.682832444256, 5302.0, 5669.433056694297, 5676.105186354906, 5690.032694442038, 5708.914366284592, 5000.574951129195, 5332.440093790251, 6000.489958353589, 6019.652335589211, 6024.638521688713, 3255.674432556725, 3595.676389124991, 3790.195255510847, 3843.1545060086737, 3853.133045064838, 5221.712805795718, 5696.401877802771, 5704.0, 5740.0, 5774.2782152230975, 5328.880935003693, 5522.570069169553, 5566.24855644499, 5577.972110139423, 5582.441755824385, 4826.91394436249, 5336.412994570516, 5558.6381336572795, 5562.276904002454, 5591.832245032656, 4767.951050768826, 4975.57707594844, 5223.608229382888, 5418.566514678895, 5442.564594832483, 4934.309196712391, 5290.650884024549, 5344.839654810206, 5352.625316227983, 5486.862828429162, 4609.677322587387, 5082.923756143735, 5347.251384806208, 5510.118381059011, 5510.311211098613], "value": 4017.277028222531, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.1599222936362, 774.6436639145993, 825.3685930263349, 838.0446291228005, 838.291643561191, 14460.493882714094, 15113.622159446018, 15122.697546049072, 15124.621884452867, 15129.940904136729, 13729.038967272294, 13827.65430864229, 13858.93070534646, 13859.267591551055, 13877.028607997445, 617.9999999999989, 741.280957471252, 887.1394747095339, 918.5269586163131, 925.4909799610222, 905.6377449020412, 917.954102294886, 919.8666193401953, 921.8571121476182, 925.1257561604286, 978.0122076702546, 982.1602529836994, 982.4841957972051, 987.0918754745651, 987.239825334492, 667.0561155964305, 933.6078846884291, 963.4219468319022, 963.5134257200069, 965.8792650918635, 727.432602569991, 727.7671145233527, 728.3735987051132, 728.6611725547646, 729.2452311857203, 14370.065945713495, 14425.927870360581, 14437.0, 14454.638634034129, 14511.274436278143, 13456.259905705183, 13529.932350338284, 13531.458741650322, 13560.186388816703, 13704.65738356539, 12180.756384872342, 12203.0, 12262.81605775914, 12398.132130750852, 12727.74544509114, 4472.217361961676, 4485.0, 4620.237660785966, 4657.860264192046, 4667.789949452283, 83198.58400707958, 85068.57465712632, 85402.14597854066, 85945.0, 86066.0, 10728.731781705364, 10735.463226838627, 10823.675289741162, 10848.18638602108, 10902.236843420884, 19406.417807465798, 20022.099005544784, 20075.899620501805, 20609.0, 20637.690434643493, 7036.859262814715, 7536.472446928662, 8008.119106898236, 8039.557824319601, 8061.153578874134, 4401.382153490752, 4461.800300695136, 4537.228671125917, 4558.339040839126, 4585.358049873021, 3337.983310083434, 3417.863285468603, 3442.5868895732674, 3444.8622055118017, 3449.448088305859, 580.692233116449, 595.8152972578514, 602.1750202222983, 609.6372658268323, 609.6494515653428, 416.86451903131604, 657.9013148027834, 685.6871465817104, 700.4396482813729, 729.3363039633907, 8521.914780852236, 9023.81952360949, 9088.136627020422, 9252.444853308823, 9327.207187389013, 645.6610279603245, 657.3032585459429, 670.5172275961397, 677.4174210179242, 682.2427105912533, 4296.82812687489, 4378.652821859362, 4379.146066516999, 4381.802818873159, 4509.864704058883, 88563.55718221496, 89772.55113724389, 90965.0, 91349.08650913405, 91524.54237728902, 78733.0, 80512.0, 80698.0, 81965.59017204876, 82982.75525867117, 73030.63484682543, 73167.0, 73821.0, 74344.0, 75117.62441187869, 8558.621238023337, 8599.484030958163, 8627.6980305689, 8628.352873534635, 8900.0, 45665.543344566126, 45776.771116144206, 45858.0, 46473.0, 46872.82817929579, 57398.713006436374, 57476.71261643832, 63654.0, 64165.0, 64208.67895660492, 9828.655997040069, 11341.0, 11383.943080284545, 11466.885331146581, 12132.636020919386, 11978.64064078079, 12367.814482782946, 12444.75110497767, 12628.747425051632, 12972.09195356316, 11821.586244481401, 11831.40842957866, 11834.408279585814, 11911.642650720492, 12120.212186207867, 10346.793064138825, 11686.18196726221, 11839.230450020721, 12111.636650900487, 12360.320182389694, 10051.145652619609, 11130.833037504282, 11444.599439019927, 11715.824262636237, 11851.940740296588, 9105.0, 9366.95316523413, 9380.718578442658, 9509.0, 9590.184834288884, 8127.2279133482225, 9223.262139028728, 9801.362911410735, 9818.950905245429, 9824.607015719292], "value": 7127.819212484715, "unit": "score"}], "name": "dromaeo_css", "value": 7216.126200359377, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 111.0, 112.0, 111.0, 112.0, 112.0, 112.0, 111.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 148.0, 142.0, 169.0, 131.0, 131.0, 158.0, 144.0, 180.0, 143.0], "value": 152.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 183.0, 184.0, 183.0, 184.0, 188.0, 185.0, 184.0, 182.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 57.0, 86.0, 62.0, 58.0, 61.0, 67.0, 83.0, 58.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 94.0, 92.0, 73.0, 70.0, 69.0, 96.0, 71.0, 68.0, 103.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 84.0, 86.0, 84.0, 87.0, 84.0, 86.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 83.0, 84.0, 86.0, 86.0, 89.0, 84.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 54.0, 53.0, 54.0, 54.0, 55.0, 55.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 78.0, 72.0, 73.0, 70.0, 69.0, 69.0, 70.0, 71.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 96.0, 106.0, 106.0, 108.0, 144.0, 87.0], "value": 99.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 139.0, 139.0, 137.0, 136.0, 137.0, 134.0, 157.0, 140.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 225.0, 198.0, 162.0, 167.0, 165.0, 163.0, 164.0, 164.0, 162.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1465.3000000000002, "alertThreshold": 2.0}]} 08:56:36 INFO - Return code: 0 08:56:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:56:36 ERROR - # TBPL SUCCESS # 08:56:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:56:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:56:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:56:36 INFO - 2016-05-30 08:56:36.922 screenresolution[1678:9715] starting screenresolution argv=screenresolution get 08:56:36 INFO - 2016-05-30 08:56:36.925 screenresolution[1678:9715] Display 0: 1600x1200x32@60 08:56:36 INFO - 2016-05-30 08:56:36.931 screenresolution[1679:9718] starting screenresolution argv=screenresolution list 08:56:36 INFO - Available Modes on Display 0 08:56:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:56:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:56:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:56:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:56:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:56:37 INFO - Intel Iris: 08:56:37 INFO - Chipset Model: Intel Iris 08:56:37 INFO - Type: GPU 08:56:37 INFO - Bus: Built-In 08:56:37 INFO - VRAM (Dynamic, Max): 1536 MB 08:56:37 INFO - Vendor: Intel (0x8086) 08:56:37 INFO - Device ID: 0x0a2e 08:56:37 INFO - Revision ID: 0x0009 08:56:37 INFO - Displays: 08:56:37 INFO - Display: 08:56:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:56:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:56:37 INFO - Display Serial Number: 1600x1200 60 08:56:37 INFO - Main Display: Yes 08:56:37 INFO - Mirror: Off 08:56:37 INFO - Online: Yes 08:56:37 INFO - Rotation: Supported 08:56:37 INFO - Return code: 0 08:56:37 INFO - Running post-action listener: _resource_record_post_action 08:56:37 INFO - ##### 08:56:37 INFO - ##### Finished run-tests step (success) 08:56:37 INFO - ##### 08:56:37 INFO - Running post-run listener: _resource_record_post_run 08:56:37 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36914688; Write bytes: 696577024; Read time: 239; Write time: 1961 08:56:37 INFO - TinderboxPrint: CPU usage
53.9% 08:56:37 INFO - TinderboxPrint: I/O read bytes / time
36,914,688 / 239 08:56:37 INFO - TinderboxPrint: I/O write bytes / time
696,577,024 / 1,961 08:56:37 INFO - TinderboxPrint: CPU idle
2,235.0 (46.1%) 08:56:37 INFO - TinderboxPrint: CPU system
967.8 (20.0%) 08:56:37 INFO - TinderboxPrint: CPU user
1,642.8 (33.9%) 08:56:37 INFO - TinderboxPrint: Swap in / out
436,105,216 / 0 08:56:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400171520; Write bytes: 401561088; Read time: 30131; Write time: 873 08:56:37 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35100160; Write bytes: 294163968; Read time: 224; Write time: 1085 08:56:37 INFO - Running post-run listener: _upload_blobber_files 08:56:37 INFO - Blob upload gear active. 08:56:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:56:37 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 08:56:37 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:56:37 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:56:38 INFO - (blobuploader) - INFO - Open directory for files ... 08:56:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:56:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:56:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:39 INFO - (blobuploader) - INFO - Done attempting. 08:56:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:56:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:56:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:40 INFO - (blobuploader) - INFO - Done attempting. 08:56:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:56:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:56:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:42 INFO - (blobuploader) - INFO - Done attempting. 08:56:42 INFO - (blobuploader) - INFO - Iteration through files over. 08:56:42 INFO - Return code: 0 08:56:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:56:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:56:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/583e9cd7e323b0b258cd2c2b390e98714f4dbf77cef6ae85f1ca4251c8f0ddc56fd446bdfae10d05ac8e40924c981236ba268ea26659a3026543d80c42decb95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b56f8ae9ee74dcfbe44be7963af43a78fa75d34874a16a26c42a889bc832603cbaed47a1391dd9b541e1f62a65f9d760e689ada182c894a092f95fcb4161d175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff92e8b41c377fd1f54f5982a6c6b8941cb7e0f43a28917629b225c9cf425f2350807d69dc0996b774014fe66fefb8a0657c6749cb3868f1c28bd79f148ecf18"} 08:56:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:56:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:56:42 INFO - Contents: 08:56:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/583e9cd7e323b0b258cd2c2b390e98714f4dbf77cef6ae85f1ca4251c8f0ddc56fd446bdfae10d05ac8e40924c981236ba268ea26659a3026543d80c42decb95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b56f8ae9ee74dcfbe44be7963af43a78fa75d34874a16a26c42a889bc832603cbaed47a1391dd9b541e1f62a65f9d760e689ada182c894a092f95fcb4161d175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff92e8b41c377fd1f54f5982a6c6b8941cb7e0f43a28917629b225c9cf425f2350807d69dc0996b774014fe66fefb8a0657c6749cb3868f1c28bd79f148ecf18"} 08:56:42 INFO - Running post-run listener: copy_logs_to_upload_dir 08:56:42 INFO - Copying logs to upload dir... 08:56:42 INFO - mkdir: /builds/slave/test/build/upload/logs 08:56:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.277959 ========= master_lag: 0.62 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-30 08:56:43.470458) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:56:43.474125) ========= 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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/583e9cd7e323b0b258cd2c2b390e98714f4dbf77cef6ae85f1ca4251c8f0ddc56fd446bdfae10d05ac8e40924c981236ba268ea26659a3026543d80c42decb95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b56f8ae9ee74dcfbe44be7963af43a78fa75d34874a16a26c42a889bc832603cbaed47a1391dd9b541e1f62a65f9d760e689ada182c894a092f95fcb4161d175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff92e8b41c377fd1f54f5982a6c6b8941cb7e0f43a28917629b225c9cf425f2350807d69dc0996b774014fe66fefb8a0657c6749cb3868f1c28bd79f148ecf18"} build_url:https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/IRxjt9u5S5yyEzHo2__tOA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/583e9cd7e323b0b258cd2c2b390e98714f4dbf77cef6ae85f1ca4251c8f0ddc56fd446bdfae10d05ac8e40924c981236ba268ea26659a3026543d80c42decb95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b56f8ae9ee74dcfbe44be7963af43a78fa75d34874a16a26c42a889bc832603cbaed47a1391dd9b541e1f62a65f9d760e689ada182c894a092f95fcb4161d175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff92e8b41c377fd1f54f5982a6c6b8941cb7e0f43a28917629b225c9cf425f2350807d69dc0996b774014fe66fefb8a0657c6749cb3868f1c28bd79f148ecf18"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:56:43.515782) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:56:43.516064) ========= 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.Xf7owFzfvZ/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.LsQOqdtxqF/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.005134 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:56:43.567690) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:56:43.568020) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:56:43.568333) ========= ========= Total master_lag: 0.76 =========