builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0373 starttime: 1464266601.19 results: success (0) buildid: 20160526034040 builduid: 259772971e3643829b0cca2a0d7d0847 revision: 02517c2c78bd6da0d0433b6b549c09e9a43a9895 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.190917) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.191349) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.191642) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.256636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.256887) ========= 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.17y6YEG56F/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.6EcyhVUY0Z/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-26 05:43:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.6M=0.001s 2016-05-26 05:43:21 (17.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152868 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.446096) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.446382) ========= 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.17y6YEG56F/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.6EcyhVUY0Z/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.025925 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.493971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:21.494282) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 02517c2c78bd6da0d0433b6b549c09e9a43a9895 --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 02517c2c78bd6da0d0433b6b549c09e9a43a9895 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.17y6YEG56F/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.6EcyhVUY0Z/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-26 05:43:21,548 truncating revision to first 12 chars 2016-05-26 05:43:21,548 Setting DEBUG logging. 2016-05-26 05:43:21,548 attempt 1/10 2016-05-26 05:43:21,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/02517c2c78bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 05:43:22,215 unpacking tar archive at: fx-team-02517c2c78bd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:22.443324) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:22.443870) ========= script_repo_revision: 02517c2c78bd6da0d0433b6b549c09e9a43a9895 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:22.444554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:22.445033) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 05:43:22.465749) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 40 secs) (at 2016-05-26 05:43:22.466091) ========= /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.17y6YEG56F/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.6EcyhVUY0Z/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:43:22 INFO - MultiFileLogger online at 20160526 05:43:22 in /builds/slave/test 05:43:22 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 05:43:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:43:22 INFO - {'append_to_log': False, 05:43:22 INFO - 'base_work_dir': '/builds/slave/test', 05:43:22 INFO - 'blob_upload_branch': 'Fx-Team', 05:43:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:43:22 INFO - 'branch': 'Fx-Team', 05:43:22 INFO - 'buildbot_json_path': 'buildprops.json', 05:43:22 INFO - 'config_files': ('talos/mac_config.py',), 05:43:22 INFO - 'default_actions': ('clobber', 05:43:22 INFO - 'read-buildbot-config', 05:43:22 INFO - 'download-and-extract', 05:43:22 INFO - 'populate-webroot', 05:43:22 INFO - 'create-virtualenv', 05:43:22 INFO - 'install', 05:43:22 INFO - 'run-tests'), 05:43:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:43:22 INFO - 'download_minidump_stackwalk': True, 05:43:22 INFO - 'download_symbols': 'ondemand', 05:43:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:43:22 INFO - 'tooltool.py': '/tools/tooltool.py', 05:43:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:43:22 INFO - '/tools/misc-python/virtualenv.py')}, 05:43:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:43:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:43:22 INFO - 'installer_path': 'installer.exe', 05:43:22 INFO - 'log_level': 'info', 05:43:22 INFO - 'log_name': 'talos', 05:43:22 INFO - 'log_to_console': True, 05:43:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:43:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:43:22 INFO - 'opt_config_files': (), 05:43:22 INFO - 'pip_index': False, 05:43:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:43:22 INFO - 'cmd': ('bash', 05:43:22 INFO - '-c', 05:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:43:22 INFO - 'enabled': True, 05:43:22 INFO - 'halt_on_failure': False, 05:43:22 INFO - 'name': 'check_screen_resolution'},), 05:43:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:43:22 INFO - 'cmd': ('bash', 05:43:22 INFO - '-c', 05:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:43:22 INFO - 'enabled': True, 05:43:22 INFO - 'halt_on_failure': False, 05:43:22 INFO - 'name': 'check_screen_resolution'},), 05:43:22 INFO - 'run_cmd_checks_enabled': True, 05:43:22 INFO - 'sps_profile': False, 05:43:22 INFO - 'sps_profile_interval': 0, 05:43:22 INFO - 'suite': 'dromaeojs', 05:43:22 INFO - 'system_bits': '32', 05:43:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:43:22 INFO - 'title': 't-yosemite-r7-0373', 05:43:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:43:22 INFO - 'use_talos_json': True, 05:43:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:43:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:43:22 INFO - 'work_dir': 'build'} 05:43:22 INFO - ##### 05:43:22 INFO - ##### Running clobber step. 05:43:22 INFO - ##### 05:43:22 INFO - Running pre-action listener: _resource_record_pre_action 05:43:22 INFO - Running main action method: clobber 05:43:22 INFO - rmtree: /builds/slave/test/build 05:43:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:43:24 INFO - Running post-action listener: _resource_record_post_action 05:43:24 INFO - ##### 05:43:24 INFO - ##### Finished clobber step (success) 05:43:24 INFO - ##### 05:43:24 INFO - ##### 05:43:24 INFO - ##### Running read-buildbot-config step. 05:43:24 INFO - ##### 05:43:24 INFO - Running pre-action listener: _resource_record_pre_action 05:43:24 INFO - Running main action method: read_buildbot_config 05:43:24 INFO - Using buildbot properties: 05:43:24 INFO - { 05:43:24 INFO - "project": "", 05:43:24 INFO - "product": "firefox", 05:43:24 INFO - "script_repo_revision": "production", 05:43:24 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:43:24 INFO - "repository": "", 05:43:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:43:24 INFO - "buildid": "20160526034040", 05:43:24 INFO - "pgo_build": "False", 05:43:24 INFO - "basedir": "/builds/slave/test", 05:43:24 INFO - "buildnumber": 35, 05:43:24 INFO - "slavename": "t-yosemite-r7-0373", 05:43:24 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:43:24 INFO - "platform": "yosemite_r7", 05:43:24 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:43:24 INFO - "branch": "fx-team", 05:43:24 INFO - "repo_path": "integration/fx-team", 05:43:24 INFO - "revision": "02517c2c78bd6da0d0433b6b549c09e9a43a9895", 05:43:24 INFO - "stage_platform": "macosx64", 05:43:24 INFO - "builduid": "259772971e3643829b0cca2a0d7d0847", 05:43:24 INFO - "slavebuilddir": "test" 05:43:24 INFO - } 05:43:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:43:24 INFO - Running post-action listener: _resource_record_post_action 05:43:24 INFO - ##### 05:43:24 INFO - ##### Finished read-buildbot-config step (success) 05:43:24 INFO - ##### 05:43:24 INFO - ##### 05:43:24 INFO - ##### Running download-and-extract step. 05:43:24 INFO - ##### 05:43:24 INFO - Running pre-action listener: _resource_record_pre_action 05:43:24 INFO - Running main action method: download_and_extract 05:43:24 INFO - mkdir: /builds/slave/test/build/tests 05:43:24 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')]} 05:43:24 INFO - https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:43:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:43:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:43:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:43:46 INFO - Downloaded 1199 bytes. 05:43:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:43:46 INFO - Using the following test package requirements: 05:43:46 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:43:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:43:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:43:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'jsshell-mac.zip'], 05:43:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:43:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:43:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:43:46 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:43:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:43:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:43:46 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:43:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:43: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')]} 05:43:46 INFO - https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:43:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:43:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:43:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:43:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:43:49 INFO - Downloaded 18622448 bytes. 05:43:49 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'] 05:43:49 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 05:43:52 INFO - Return code: 0 05:43:52 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 05:43:52 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')]} 05:43:52 INFO - https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:43:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:43:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:43:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:43:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:44:01 INFO - Downloaded 18622448 bytes. 05:44:01 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'] 05:44:01 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 05:44:04 INFO - Return code: 0 05:44:04 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')]} 05:44:04 INFO - https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:44:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:44:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:44:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:44:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:44:05 INFO - Downloaded 10834034 bytes. 05:44:05 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'] 05:44:05 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 05:44:06 INFO - Return code: 0 05:44:06 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')]} 05:44:06 INFO - https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:44:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:44:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:44:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:44:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c7jxW3F_TJWSAuegYG49gQ/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 05:44:09 INFO - Downloaded 110502298 bytes. 05:44:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:44:09 INFO - mkdir: /builds/slave/test/properties 05:44:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:44:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:44:09 INFO - Contents: 05:44:09 INFO - build_url:https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:44:10 INFO - Running post-action listener: _resource_record_post_action 05:44:10 INFO - Running post-action listener: set_extra_try_arguments 05:44:10 INFO - ##### 05:44:10 INFO - ##### Finished download-and-extract step (success) 05:44:10 INFO - ##### 05:44:10 INFO - ##### 05:44:10 INFO - ##### Running populate-webroot step. 05:44:10 INFO - ##### 05:44:10 INFO - Running pre-action listener: _resource_record_pre_action 05:44:10 INFO - Running main action method: populate_webroot 05:44:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:44:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:44:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:44:10 INFO - '--tppagecycles', 05:44:10 INFO - '7'], 05:44:10 INFO - 'tests': ['tsvgm']}}, 05:44:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:44:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:44:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:44:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:44:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['damp', 'tps']}, 05:44:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'tests': ['damp', 'tps']}, 05:44:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['dromaeo_dom']}, 05:44:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:44:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['a11yr', 05:44:10 INFO - 'ts_paint', 05:44:10 INFO - 'tpaint', 05:44:10 INFO - 'sessionrestore', 05:44:10 INFO - 'sessionrestore_no_auto_restore', 05:44:10 INFO - 'tabpaint']}, 05:44:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:44:10 INFO - 'ts_paint', 05:44:10 INFO - 'tpaint', 05:44:10 INFO - 'sessionrestore', 05:44:10 INFO - 'sessionrestore_no_auto_restore', 05:44:10 INFO - 'tabpaint']}, 05:44:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:44:10 INFO - 'ts_paint', 05:44:10 INFO - 'tpaint', 05:44:10 INFO - 'sessionrestore', 05:44:10 INFO - 'sessionrestore_no_auto_restore', 05:44:10 INFO - 'tabpaint']}, 05:44:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:44:10 INFO - 'ts_paint', 05:44:10 INFO - 'tpaint', 05:44:10 INFO - 'sessionrestore', 05:44:10 INFO - 'sessionrestore_no_auto_restore', 05:44:10 INFO - 'tabpaint']}, 05:44:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['a11yr', 05:44:10 INFO - 'ts_paint', 05:44:10 INFO - 'tpaint', 05:44:10 INFO - 'sessionrestore', 05:44:10 INFO - 'sessionrestore_no_auto_restore', 05:44:10 INFO - 'tabpaint']}, 05:44:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['a11yr', 05:44:10 INFO - 'ts_paint', 05:44:10 INFO - 'tpaint', 05:44:10 INFO - 'sessionrestore', 05:44:10 INFO - 'sessionrestore_no_auto_restore', 05:44:10 INFO - 'tabpaint']}, 05:44:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['tsvgx', 05:44:10 INFO - 'tsvgr_opacity', 05:44:10 INFO - 'tart', 05:44:10 INFO - 'tscrollx', 05:44:10 INFO - 'cart']}, 05:44:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:44:10 INFO - 'tsvgr_opacity', 05:44:10 INFO - 'tart', 05:44:10 INFO - 'tscrollx', 05:44:10 INFO - 'cart']}, 05:44:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'talos_options': ['--disable-e10s'], 05:44:10 INFO - 'tests': ['tp5o']}, 05:44:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'tests': ['tp5o']}, 05:44:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'talos_options': ['--disable-e10s', 05:44:10 INFO - '--xperf_path', 05:44:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:10 INFO - 'tests': ['tp5n']}, 05:44:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:10 INFO - 'talos_options': ['--xperf_path', 05:44:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:10 INFO - 'tests': ['tp5n']}}, 05:44:10 INFO - 'talos.zip': {'path': '', 05:44:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:44:10 INFO - Running post-action listener: _resource_record_post_action 05:44:10 INFO - ##### 05:44:10 INFO - ##### Finished populate-webroot step (success) 05:44:10 INFO - ##### 05:44:10 INFO - ##### 05:44:10 INFO - ##### Running create-virtualenv step. 05:44:10 INFO - ##### 05:44:10 INFO - Running pre-action listener: _resource_record_pre_action 05:44:10 INFO - Running main action method: create_virtualenv 05:44:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:44:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:44:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:44:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:44:10 INFO - Using real prefix '/tools/python27' 05:44:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:44:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:44:14 INFO - Installing pip.................done. 05:44:14 INFO - Return code: 0 05:44:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:44:14 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')]} 05:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:14 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')]} 05:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:14 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:14 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 05:44:14 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 05:44:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:14 INFO - 'HOME': '/Users/cltbld', 05:44:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:14 INFO - 'LOGNAME': 'cltbld', 05:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:14 INFO - 'MOZ_NO_REMOTE': '1', 05:44:14 INFO - 'NO_EM_RESTART': '1', 05:44:14 INFO - 'PAGER': '/bin/cat', 05:44:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:14 INFO - 'PWD': '/builds/slave/test', 05:44:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:14 INFO - 'SHELL': '/bin/bash', 05:44:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:14 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:14 INFO - 'USER': 'cltbld', 05:44:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:14 INFO - 'XPC_FLAGS': '0x0', 05:44:14 INFO - 'XPC_SERVICE_NAME': '0', 05:44:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:44:14 INFO - Cleaning up... 05:44:14 INFO - Return code: 0 05:44:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:44:14 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')]} 05:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:14 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')]} 05:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:14 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:14 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 05:44:14 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 05:44:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:14 INFO - 'HOME': '/Users/cltbld', 05:44:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:14 INFO - 'LOGNAME': 'cltbld', 05:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:14 INFO - 'MOZ_NO_REMOTE': '1', 05:44:14 INFO - 'NO_EM_RESTART': '1', 05:44:14 INFO - 'PAGER': '/bin/cat', 05:44:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:14 INFO - 'PWD': '/builds/slave/test', 05:44:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:14 INFO - 'SHELL': '/bin/bash', 05:44:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:14 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:14 INFO - 'USER': 'cltbld', 05:44:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:14 INFO - 'XPC_FLAGS': '0x0', 05:44:14 INFO - 'XPC_SERVICE_NAME': '0', 05:44:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:15 INFO - Downloading/unpacking psutil>=3.1.1 05:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:15 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 05:44:15 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 05:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:44:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:44:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:44:17 INFO - Installing collected packages: psutil 05:44:17 INFO - Running setup.py install for psutil 05:44:17 INFO - building 'psutil._psutil_osx' extension 05:44:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:44:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:44:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:44:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:44:18 INFO - building 'psutil._psutil_posix' extension 05:44:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:44:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:44:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:44:18 INFO - ^ 05:44:18 INFO - 1 warning generated. 05:44:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:44:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:44:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:44:19 INFO - Successfully installed psutil 05:44:19 INFO - Cleaning up... 05:44:19 INFO - Return code: 0 05:44:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:44:19 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')]} 05:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:19 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')]} 05:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:19 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:19 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 05:44:19 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 05:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:19 INFO - 'HOME': '/Users/cltbld', 05:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:19 INFO - 'LOGNAME': 'cltbld', 05:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:19 INFO - 'MOZ_NO_REMOTE': '1', 05:44:19 INFO - 'NO_EM_RESTART': '1', 05:44:19 INFO - 'PAGER': '/bin/cat', 05:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:19 INFO - 'PWD': '/builds/slave/test', 05:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:19 INFO - 'SHELL': '/bin/bash', 05:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:19 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:19 INFO - 'USER': 'cltbld', 05:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:19 INFO - 'XPC_FLAGS': '0x0', 05:44:19 INFO - 'XPC_SERVICE_NAME': '0', 05:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:44: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 05:44: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 05:44: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 05:44: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 05:44: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 05:44: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 05:44:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:44:21 INFO - Installing collected packages: mozsystemmonitor 05:44:21 INFO - Running setup.py install for mozsystemmonitor 05:44:22 INFO - Successfully installed mozsystemmonitor 05:44:22 INFO - Cleaning up... 05:44:22 INFO - Return code: 0 05:44:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:44:22 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')]} 05:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:22 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')]} 05:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:22 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:22 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 05:44:22 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 05:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:22 INFO - 'HOME': '/Users/cltbld', 05:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:22 INFO - 'LOGNAME': 'cltbld', 05:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:22 INFO - 'MOZ_NO_REMOTE': '1', 05:44:22 INFO - 'NO_EM_RESTART': '1', 05:44:22 INFO - 'PAGER': '/bin/cat', 05:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:22 INFO - 'PWD': '/builds/slave/test', 05:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:22 INFO - 'SHELL': '/bin/bash', 05:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:22 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:22 INFO - 'USER': 'cltbld', 05:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:22 INFO - 'XPC_FLAGS': '0x0', 05:44:22 INFO - 'XPC_SERVICE_NAME': '0', 05:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:22 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 05:44:22 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 05:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:24 INFO - Downloading blobuploader-1.2.4.tar.gz 05:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:44:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:44: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 05:44: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 05:44: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 05:44: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 05:44: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 05:44: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 05:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:44:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:25 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 05:44:25 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 05:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:25 INFO - Downloading docopt-0.6.1.tar.gz 05:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:44:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:44:26 INFO - Running setup.py install for blobuploader 05:44:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:44:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:44:26 INFO - Running setup.py install for requests 05:44:26 INFO - Running setup.py install for docopt 05:44:26 INFO - Successfully installed blobuploader requests docopt 05:44:26 INFO - Cleaning up... 05:44:26 INFO - Return code: 0 05:44:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44:26 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')]} 05:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:27 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')]} 05:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:27 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:27 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 05:44:27 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 05:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:27 INFO - 'HOME': '/Users/cltbld', 05:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:27 INFO - 'LOGNAME': 'cltbld', 05:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:27 INFO - 'MOZ_NO_REMOTE': '1', 05:44:27 INFO - 'NO_EM_RESTART': '1', 05:44:27 INFO - 'PAGER': '/bin/cat', 05:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:27 INFO - 'PWD': '/builds/slave/test', 05:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:27 INFO - 'SHELL': '/bin/bash', 05:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:27 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:27 INFO - 'USER': 'cltbld', 05:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:27 INFO - 'XPC_FLAGS': '0x0', 05:44:27 INFO - 'XPC_SERVICE_NAME': '0', 05:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:44:27 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Ez6uOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:44:27 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-29pCiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:44:27 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-HJSvPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:44:27 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-qZBQhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-SaoE2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-U4NEtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-VZQ2_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-9IsAmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-lWElXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-wLuzgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-C1fjyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-QPSMXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-n3BjpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:44:28 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-6bIk2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:29 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-98n1BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:44:29 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-QmeIKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:44:29 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-hKFOZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:44:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:44:29 INFO - Running setup.py install for manifestparser 05:44:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:44:29 INFO - Running setup.py install for mozcrash 05:44:29 INFO - Running setup.py install for mozdebug 05:44:29 INFO - Running setup.py install for mozdevice 05:44:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:44:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:44:29 INFO - Running setup.py install for mozfile 05:44:30 INFO - Running setup.py install for mozhttpd 05:44:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Running setup.py install for mozinfo 05:44:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Running setup.py install for mozInstall 05:44:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Running setup.py install for mozleak 05:44:30 INFO - Running setup.py install for mozlog 05:44:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:44:30 INFO - Running setup.py install for moznetwork 05:44:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:44:31 INFO - Running setup.py install for mozprocess 05:44:31 INFO - Running setup.py install for mozprofile 05:44:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:44:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:44:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:44:31 INFO - Running setup.py install for mozrunner 05:44:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:44:31 INFO - Running setup.py install for mozscreenshot 05:44:31 INFO - Running setup.py install for moztest 05:44:31 INFO - Running setup.py install for mozversion 05:44:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:44:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:44:31 INFO - Cleaning up... 05:44:31 INFO - Return code: 0 05:44:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44:31 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')]} 05:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:31 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')]} 05:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:31 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:31 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 05:44:31 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 05:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:31 INFO - 'HOME': '/Users/cltbld', 05:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:31 INFO - 'LOGNAME': 'cltbld', 05:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:31 INFO - 'MOZ_NO_REMOTE': '1', 05:44:31 INFO - 'NO_EM_RESTART': '1', 05:44:31 INFO - 'PAGER': '/bin/cat', 05:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:31 INFO - 'PWD': '/builds/slave/test', 05:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:31 INFO - 'SHELL': '/bin/bash', 05:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:31 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:31 INFO - 'USER': 'cltbld', 05:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:31 INFO - 'XPC_FLAGS': '0x0', 05:44:31 INFO - 'XPC_SERVICE_NAME': '0', 05:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-cQrNVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-jIjgig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:44:32 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)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-_azGO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-O132US-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:44:32 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)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-zikjYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-kSZLZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-hDjszh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:44:32 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)) 05:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:44:32 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Hy9p2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:44:33 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)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-oJ9FZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-UJqdwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:44:33 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)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-FDK09P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-7ksd_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:44:33 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)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-YD93na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-vtrKLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:44:33 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)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-F5jHz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-pPkZoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:44:33 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)) 05:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:44:33 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-xN8PxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:44:34 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)) 05:44:34 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)) 05:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:44:34 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)) 05:44:34 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)) 05:44:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:34 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 05:44:34 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 05:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:36 INFO - Downloading blessings-1.6.tar.gz 05:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:44:36 INFO - Installing collected packages: blessings 05:44:36 INFO - Running setup.py install for blessings 05:44:36 INFO - Successfully installed blessings 05:44:36 INFO - Cleaning up... 05:44:36 INFO - Return code: 0 05:44:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:37 INFO - Reading from file tmpfile_stdout 05:44:37 INFO - Current package versions: 05:44:37 INFO - blessings == 1.6 05:44:37 INFO - blobuploader == 1.2.4 05:44:37 INFO - docopt == 0.6.1 05:44:37 INFO - manifestparser == 1.1 05:44:37 INFO - mozInstall == 1.12 05:44:37 INFO - mozcrash == 0.17 05:44:37 INFO - mozdebug == 0.1 05:44:37 INFO - mozdevice == 0.48 05:44:37 INFO - mozfile == 1.2 05:44:37 INFO - mozhttpd == 0.7 05:44:37 INFO - mozinfo == 0.9 05:44:37 INFO - mozleak == 0.1 05:44:37 INFO - mozlog == 3.2 05:44:37 INFO - moznetwork == 0.27 05:44:37 INFO - mozprocess == 0.22 05:44:37 INFO - mozprofile == 0.28 05:44:37 INFO - mozrunner == 6.11 05:44:37 INFO - mozscreenshot == 0.1 05:44:37 INFO - mozsystemmonitor == 0.3 05:44:37 INFO - moztest == 0.7 05:44:37 INFO - mozversion == 1.4 05:44:37 INFO - psutil == 3.1.1 05:44:37 INFO - requests == 1.2.3 05:44:37 INFO - wsgiref == 0.1.2 05:44:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44:37 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')]} 05:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:37 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')]} 05:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:37 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:37 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 05:44:37 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 05:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:37 INFO - 'HOME': '/Users/cltbld', 05:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:37 INFO - 'LOGNAME': 'cltbld', 05:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:37 INFO - 'MOZ_NO_REMOTE': '1', 05:44:37 INFO - 'NO_EM_RESTART': '1', 05:44:37 INFO - 'PAGER': '/bin/cat', 05:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:37 INFO - 'PWD': '/builds/slave/test', 05:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:37 INFO - 'SHELL': '/bin/bash', 05:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:37 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:37 INFO - 'USER': 'cltbld', 05:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:37 INFO - 'XPC_FLAGS': '0x0', 05:44:37 INFO - 'XPC_SERVICE_NAME': '0', 05:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:44:37 INFO - Cleaning up... 05:44:37 INFO - Return code: 0 05:44:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:44:37 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')]} 05:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:37 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')]} 05:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:37 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:37 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 05:44:37 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 05:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:37 INFO - 'HOME': '/Users/cltbld', 05:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:37 INFO - 'LOGNAME': 'cltbld', 05:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:37 INFO - 'MOZ_NO_REMOTE': '1', 05:44:37 INFO - 'NO_EM_RESTART': '1', 05:44:37 INFO - 'PAGER': '/bin/cat', 05:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:37 INFO - 'PWD': '/builds/slave/test', 05:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:37 INFO - 'SHELL': '/bin/bash', 05:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:37 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:37 INFO - 'USER': 'cltbld', 05:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:37 INFO - 'XPC_FLAGS': '0x0', 05:44:37 INFO - 'XPC_SERVICE_NAME': '0', 05:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:37 INFO - Downloading/unpacking jsonschema 05:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:37 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 05:44:37 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 05:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:44:40 INFO - Downloading/unpacking functools32 (from jsonschema) 05:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:40 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 05:44:40 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 05:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:40 INFO - Downloading functools32-3.2.3-2.tar.gz 05:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:44:40 INFO - warning: no files found matching '*.txt' 05:44:40 INFO - no previously-included directories found matching 'build' 05:44:40 INFO - no previously-included directories found matching 'dist' 05:44:40 INFO - no previously-included directories found matching '.git*' 05:44:40 INFO - Installing collected packages: jsonschema, functools32 05:44:40 INFO - Running setup.py install for functools32 05:44:40 INFO - warning: no files found matching '*.txt' 05:44:40 INFO - no previously-included directories found matching 'build' 05:44:40 INFO - no previously-included directories found matching 'dist' 05:44:40 INFO - no previously-included directories found matching '.git*' 05:44:40 INFO - Successfully installed jsonschema functools32 05:44:40 INFO - Cleaning up... 05:44:40 INFO - Return code: 0 05:44:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:44:40 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')]} 05:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:40 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')]} 05:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:40 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 0x10379ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037a4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7d8d5df00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, '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.17y6YEG56F/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.6EcyhVUY0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:44:40 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 05:44:40 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 05:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:40 INFO - 'HOME': '/Users/cltbld', 05:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:40 INFO - 'LOGNAME': 'cltbld', 05:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:40 INFO - 'MOZ_NO_REMOTE': '1', 05:44:40 INFO - 'NO_EM_RESTART': '1', 05:44:40 INFO - 'PAGER': '/bin/cat', 05:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:40 INFO - 'PWD': '/builds/slave/test', 05:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:40 INFO - 'SHELL': '/bin/bash', 05:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:44:40 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:40 INFO - 'USER': 'cltbld', 05:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:40 INFO - 'XPC_FLAGS': '0x0', 05:44:40 INFO - 'XPC_SERVICE_NAME': '0', 05:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:41 INFO - Downloading/unpacking flake8 05:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:41 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 05:44:41 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 05:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:44:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:43 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 05:44:43 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 05:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:44:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:44 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 05:44:44 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 05:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:44 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 05:44:44 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 05:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:44:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:44:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:44:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:44:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:44:44 INFO - no previously-included directories found matching 'docs/_build' 05:44:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:44:45 INFO - Running setup.py install for pep8 05:44:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:44:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:44:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:44:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:44:45 INFO - no previously-included directories found matching 'docs/_build' 05:44:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:44:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:44:45 INFO - Cleaning up... 05:44:45 INFO - Return code: 0 05:44:45 INFO - Running post-action listener: _resource_record_post_action 05:44:45 INFO - Running post-action listener: _start_resource_monitoring 05:44:45 INFO - Starting resource monitoring. 05:44:45 INFO - ##### 05:44:45 INFO - ##### Finished create-virtualenv step (success) 05:44:45 INFO - ##### 05:44:45 INFO - ##### 05:44:45 INFO - ##### Running install step. 05:44:45 INFO - ##### 05:44:45 INFO - Running pre-action listener: _resource_record_pre_action 05:44:45 INFO - Running main action method: install 05:44:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:45 INFO - Reading from file tmpfile_stdout 05:44:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:44:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:44:45 INFO - Reading from file tmpfile_stdout 05:44:45 INFO - Output received: 05:44:45 INFO - Usage: mozinstall [options] installer 05:44:45 INFO - Options: 05:44:45 INFO - -h, --help show this help message and exit 05:44:45 INFO - -d DEST, --destination=DEST 05:44:45 INFO - Directory to install application into. [default: 05:44:45 INFO - "/builds/slave/test"] 05:44:45 INFO - --app=APP Application being installed. [default: firefox] 05:44:45 INFO - mkdir: /builds/slave/test/build/application 05:44:45 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'] 05:44:45 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 05:45:19 INFO - Reading from file tmpfile_stdout 05:45:19 INFO - Output received: 05:45:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:45:19 INFO - Running post-action listener: _resource_record_post_action 05:45:19 INFO - ##### 05:45:19 INFO - ##### Finished install step (success) 05:45:19 INFO - ##### 05:45:19 INFO - ##### 05:45:19 INFO - ##### Running run-tests step. 05:45:19 INFO - ##### 05:45:19 INFO - Running pre-action listener: _resource_record_pre_action 05:45:19 INFO - Running main action method: run_tests 05:45:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:45:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:45:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:45:19 INFO - 2016-05-26 05:45:19.368 screenresolution[1798:9130] starting screenresolution argv=screenresolution get 05:45:19 INFO - 2016-05-26 05:45:19.370 screenresolution[1798:9130] Display 0: 1600x1200x32@60 05:45:19 INFO - 2016-05-26 05:45:19.375 screenresolution[1799:9132] starting screenresolution argv=screenresolution list 05:45:19 INFO - Available Modes on Display 0 05:45:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:45:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:45:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:45:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:45:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:45:19 INFO - Intel Iris: 05:45:19 INFO - Chipset Model: Intel Iris 05:45:19 INFO - Type: GPU 05:45:19 INFO - Bus: Built-In 05:45:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:45:19 INFO - Vendor: Intel (0x8086) 05:45:19 INFO - Device ID: 0x0a2e 05:45:19 INFO - Revision ID: 0x0009 05:45:19 INFO - Displays: 05:45:19 INFO - Display: 05:45:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:45:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:45:19 INFO - Display Serial Number: 1600x1200 60 05:45:19 INFO - Main Display: Yes 05:45:19 INFO - Mirror: Off 05:45:19 INFO - Online: Yes 05:45:19 INFO - Rotation: Supported 05:45:19 INFO - Return code: 0 05:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:45:19 INFO - Python 2.7.3 05:45:19 INFO - Return code: 0 05:45:19 INFO - grabbing minidump binary from tooltool 05:45:19 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')]} 05:45:19 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 0x103642d30>, '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 0x103466db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103639b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:45:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:45:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:45:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:45:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:45:19 INFO - Return code: 0 05:45:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:45:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:45:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:45:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:45:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:45:20 INFO - Return code: 0 05:45:20 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/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 05:45:20 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/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0373 --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 05:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.17y6YEG56F/Render', 05:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:20 INFO - 'HOME': '/Users/cltbld', 05:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:20 INFO - 'LOGNAME': 'cltbld', 05:45:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:45:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:20 INFO - 'MOZ_NO_REMOTE': '1', 05:45:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:45:20 INFO - 'NO_EM_RESTART': '1', 05:45:20 INFO - 'PAGER': '/bin/cat', 05:45:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:20 INFO - 'PWD': '/builds/slave/test', 05:45:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:20 INFO - 'SHELL': '/bin/bash', 05:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6EcyhVUY0Z/Listeners', 05:45:20 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 05:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:20 INFO - 'USER': 'cltbld', 05:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:20 INFO - 'XPC_FLAGS': '0x0', 05:45:20 INFO - 'XPC_SERVICE_NAME': '0', 05:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:20 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/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 05:45:21 INFO - mozversion application_buildid: 20160526034040 05:45:21 INFO - mozversion application_changeset: 02517c2c78bd6da0d0433b6b549c09e9a43a9895 05:45:21 INFO - mozversion application_display_name: Nightly 05:45:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:45:21 INFO - mozversion application_name: Firefox 05:45:21 INFO - mozversion application_remotingname: firefox 05:45:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:45:21 INFO - mozversion application_vendor: Mozilla 05:45:21 INFO - mozversion application_version: 49.0a1 05:45:21 INFO - mozversion platform_buildid: 20160526034040 05:45:21 INFO - mozversion platform_changeset: 02517c2c78bd6da0d0433b6b549c09e9a43a9895 05:45:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:45:21 INFO - mozversion platform_version: 49.0a1 05:45:21 INFO - using testdate: 1464266721 05:45:21 INFO - actual date: 1464266721 05:45:21 INFO - starting webserver on 'localhost:49275' 05:45:21 INFO - SUITE-START | Running 2 tests 05:45:21 INFO - TEST-START | dromaeo_css 05:45:21 INFO - operating with platform_type : mac_ 05:45:21 INFO - Initialising browser for dromaeo_css test... 05:45:21 INFO - TEST-INFO | started process 1817 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpCLZ1KF/profile http://localhost:49275/getInfo.html) 05:45:23 INFO - PROCESS | 1817 | __metrics Screen width/height:1600/1200 05:45:23 INFO - PROCESS | 1817 | colorDepth:24 05:45:23 INFO - PROCESS | 1817 | Browser inner width/height: 1024/689 05:45:23 INFO - PROCESS | 1817 | __metrics 05:45:23 INFO - PROCESS | 1817 | 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" 05:45:23 ERROR - PROCESS | 1817 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:45:24 INFO - TEST-INFO | 1817: exit 0 05:45:24 INFO - Browser initialized. 05:45:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:45:24 INFO - TEST-INFO | started process 1820 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpCLZ1KF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:36 INFO - PROCESS | 1820 | RSS: Main: 241352704 05:45:36 INFO - PROCESS | 1820 | 05:46:27 INFO - PROCESS | 1820 | 1464266787808 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] 05:48:38 INFO - PROCESS | 1820 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 05:48:40 INFO - PROCESS | 1820 | RSS: Main: 386404352 05:48:40 INFO - PROCESS | 1820 | 05:51:43 INFO - PROCESS | 1820 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 05:51:44 INFO - PROCESS | 1820 | RSS: Main: 381767680 05:51:44 INFO - PROCESS | 1820 | 05:54:46 INFO - PROCESS | 1820 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 05:54:46 INFO - PROCESS | 1820 | RSS: Main: 375033856 05:54:46 INFO - PROCESS | 1820 | 05:57:49 INFO - PROCESS | 1820 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 05:57:49 INFO - PROCESS | 1820 | RSS: Main: 374824960 05:57:49 INFO - PROCESS | 1820 | 06:00:53 INFO - PROCESS | 1820 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:00:54 INFO - PROCESS | 1820 | RSS: Main: 340836352 06:00:54 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:03:57 INFO - PROCESS | 1820 | RSS: Main: 383811584 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | __start_tp_report 06:03:57 INFO - PROCESS | 1820 | _x_x_mozilla_page_load 06:03:57 INFO - PROCESS | 1820 | _x_x_mozilla_page_load_details 06:03:57 INFO - PROCESS | 1820 | |i|pagename|runs| 06:03:57 INFO - PROCESS | 1820 | |0;dojo.html;2966.4956957317254;3467.358538670346;3506.964930350697;3524.770889892155;3558.950109717633;5566.2207290979195;5614.410486898878;5631.60578759487;5703.0589952657765;5715.399883012281;5614.719264036802;5669.688167150805;5677.432256774331;5682.403347648489;5683.147527870821;5084.211947148188;5136.948630513703;5156.097682905476;5178.249153872686;5214.243934629477;5240.397354304247;5283.181106928413;5286.894262114755;5289.074411977907;5293.126634105368;4353.390525326441;5162;5175.948240517603;5193.766280517367;5279.70961597112;5272.841814745564;5299.046171689094;5302;5302.496262855023;5364.222187782789;5055.74721263939;5070.188769796851;5079.568236699885;5204.791808327663;5211.5830733541225;5504.146857237135;5560.555155587544;5587;5587.8882422351735;5588.133839254922;6197.566170368076;6207.6275423474735;6230.59501132425;6232.127502149703;6238.220222472191;5835.679037652927;6198.907016394758;6256.562040657156;6266.624002559861;6289.465395441393;5783.045797443375;5802.738876750556;5819.185314055993;5819.73811178498;5823.213866128103;6287.308396076428;6330.240371155491;6335.968320158462;6336.524760642878;6351.110844481776;5943.197668314809;6019.217501724747;6038.064100064497;6094.085887116879;6150.231221097363;6163.229596300463;6209.5032397408095;6211.223597050369;6219.191505104308;6222.875542489125;6091.664958427329;6113.202667687911;6119.257281746883;6139.8601398601395;6171.9691401542705;6186.10301506279;6209.285932117864;6233.688315584293;6234.096056071935;6234.1583886174785;4876.829310974099;4881.023795240895;4887.3890763653835;4888.560029597282;4918.532739389752;4326.4808223012815;4398.406215160976;4434.889127771831;4466;4472.575105364985;4820.493848145964;5626.268585083994;5699.772009119672;5710.428957104256;5733.741981610838;6237.251529816451;6252.124702541648;6264.937350626436;6268.811935641938;6270.7491700331475;5601.943980560142;5706.457886500776;5732.398098199712;5736.741846616912;5762.9135562966585;4573.153966516205;5196.68819870809;5239.345081864767;5247.842564723064;5375.5162035417;5519.779208831602;6068.75724971005;6234.9688251559355;6247.531435142473;6265.404786545271;6115.908261375994;6156.076588511759;6156.384361563808;6158.291796443466;6171.84570385744;6173.938260617337;6220.0047992321015;6223.688815559113;6259.186305780219;6264.906026409516;6198.535109866688;6203.16257305258;6206.565540412308;6219.564630475823;6231.781887633911;6245.063240513778;6252.062190671436;6257.1552840367785;6266.122742815918;6274.968625156845;5886.20536227621;5959.1657167995645;6002.249718785152;6079.118527813531;6113.266408031065;6036;6046.758129674764;6090.9390906090375;6114.60152118864;6139.140520327069;5807.506361959281;5881.705914704333;5896.823095307148;5927.8221653350465;5966.284045914518;5911.438413350725;5932.288125424977;5956.821295361146;6121.387861213843;6134.171886795226;5831.271091113611;5863.296404431325;5888.146218798336;5892.057270836645;5930.080837470199 06:03:57 INFO - PROCESS | 1820 | |1;ext.html;488.836239859647;499.6802046690118;506.1875689518323;509.6559822120068;513.7277243061179;30331.848340758293;41854;43273.78363108184;43316.350254746125;45708.3143752844;44272;44592;44597.33104003435;44702.55297447024;44812.55187448124;8538.44500107494;9486.478243696594;9720.513974301291;9738.756531086745;9743.366681165702;8523.232909038185;11477.024452921512;11614.535418583246;11692.941535292312;11961.521539138424;11576.942115289412;11627.01170400517;11631.6510504685;11640.476178571984;11655.242409243414;8600.569971501462;11143.944280278587;11519.654410367702;11747.7063073423;11835.82246266311;9823.705288841275;9907.356021858628;11698.41507924609;11770.117241206857;11805.291682498992;41166;41531.7923410384;41614.375784363256;41619.37570936408;41878.162436751096;3130.2174456385906;3152.668969758165;3199.42410366134;3707.9073023174365;3994.3409337459284;28815.9914402997;29455.11634650964;29894.953676621215;30127.69872301271;30585.388292234253;2590.3912580543556;2700.5004074246326;2718.0894400375896;2736.987314693556;2769.2938300733385;513285;515088;515784.42107789597;516063;517243.41378292866;34404.48393274103;35113.47329790055;60766;62355;62378.0643290351;36122;36257.63742362543;36372.09069773277;36403;36421.08947276339;15224.467143650141;15357.6160595986;15380.92309538445;15409.537713868378;15414.691706165813;10868.130549556017;11820.88179118215;12163.452644630835;12774.9361253195;13011.739765204833;7886.132525422199;7896.407769417317;7904.051513818264;7964.641591128413;7972.2824945754;3273.378058168988;3289.227031647561;3292.9176770580925;3296.5549650796834;3318.303156337148;937.685875231805;1108.5510368300852;1120.9663710088548;1131.8868113188614;1177.552530038597;29007;29145.271368215963;29290.560641590393;29385.85307073451;29677.406451871273;848.3298194426334;1050.2910535388583;1081.983770243447;1158.1314014489133;1185.7984142695761;14923.179225142505;14985.550433487013;15239.847601524065;15332.463363782213;15386.769198462032;597101;698034;702089;703060.4846975732;703789;61408;61580.384196157465;61836.38163618396;61890.69054654698;62131.378686212556;1318.2617734068951;1343.3753304713355;1366.9201330948638;1462.9707405851823;1482.2292407948023;24417.877910610332;30072.24819379533;30296.09111726652;30507.54238686377;30530.542041869834;51227.48772512376;51417.22874156816;51782.74108629433;51802.22296665478;51999.2200116991;470223.03104235156;470562;471247;471444;471899;22825.543489130458;23185.536289274456;25669;25807.580583068113;26469.470610588065;46238.76880615576;46462.30306545337;46734;47627.52372476231;47773.28340074832;32853.67146328506;35334;38398.42402363911;38619.03452413712;38934.80532597319;37182.256354873294;37222;37613.623863761015;38054.445740377036;38703.22593548057;28931.421371572873;37965;38079.04802379962;38266.0433489165;38432.807835960644;32452.350952980334;32881.835590821895;33528.49707254345;33729.494057588665;34114.82942585271;24923.87538062298;25011;25128.74863787696;25370.1120460783;25579.232623020594 06:03:57 INFO - PROCESS | 1820 | |2;jquery.html;381.3364745343102;382.4187235402188;386.24103636354556;399.2613664720266;412.6987299271532;20072;21674.891625541888;22078.668819967694;22082.558348833034;22233.888830555825;20457.386278411635;20791.792082079173;20814.687779683318;21468.926553672292;21482.570348593017;5170.715610641413;5850.97074514629;5936.376680448556;5959.940400596003;5971.731272092767;4140.378943158536;4375.628071613916;5939.2575928009;6299.244090709121;6660.866782664344;6521.543491955565;6620.470362371023;6621.37096975789;6624.635645039521;6626.768063117792;6418.06937993993;6623.337666233362;6639.103720997652;6659.33406659335;6674.365935236145;3700.9259814803822;3814.332491813949;4073.551909289976;5711.857203569944;5721.914171287434;25930.481390372086;26512.73487265122;26823.731762682317;26925.057622983102;27034.5944810828;1826.9086545672662;1966.6853303471376;2021;2037.7045328427444;2270.3075561953597;10113.443760593165;10385.758158348166;10409.9479502602;15104.697906041818;15489.612759680986;1663.9168041597993;1675.08707754273;1717.2959086774363;1742.538227369748;1758.0506526475688;79268.20731792666;79371.23384864425;82966.9999999994;114476.42761786221;120237;11366.659000230007;11468.655940321803;11669.76660466803;11709.473073711704;11820.113491488;14674.55976320712;14756.335964881391;14792.63018424548;14805.777913331309;14806.259687015607;8470.534120623302;8640.395172338001;8681.696140635173;8795.912040879637;8817.867731983897;8587.699430519902;8588.785280368042;8605.440646357967;8720.389572729975;8763.693270735494;4532.524084971096;4614.653900957442;4638.6752927294765;4654.301854721751;4697.694649847749;1747.560854257788;1758.384565402099;1826.278619945141;1909.6849019911838;1988.5227545389002;873.5021038008401;906.2795077913075;929.4423345992351;935.0836180543106;994.5126887825058;19553.804461955482;19792.60414791696;20218.494537636383;20264;20663.276785312442;881.7090360181124;903.1239697493421;924.075924075924;932.8554074118523;987.3138168972495;8980.416272942239;9902.55438505269;10554.894451055392;10758.731031724114;10801.135909127252;547647;580687;583299;586568;588529;91871.43680101643;93090;109820;130242;140317.2984135073;953.2278854128178;1031.83490641497;1041.7864337810731;1094.4637127807475;1098.5386137822134;17621.295148193927;17716.82283177203;18002.36991705284;18281.99449030316;18415.815841841944;47179;51170.74414627903;51515;51627.74186129045;52107.697307567614;75210;75852;76814.2318576807;77735;77769.2223077762;5477.507024367826;5891.469767720926;7343.898415237757;7569.886451703119;7690.577018264049;8972.865407019031;11943.283402995849;12131.090168237593;12229.633111006684;12326.886963070856;10289.794204115726;10486.423246721504;10531.842022369518;10671.146308295163;10808.18938579594;10202.693919182437;10263.435511046666;10450.738731531772;10706.982836630508;10843.45782710877;10492.580296788043;10827.621033263798;11047.337159770439;11098.945600167972;11172.994430501294;7832.686692532463;7893.84212315739;7978.281954624112;8056.758297251092;8100.959495202486;7897.328727058264;9516.95241523788;10563.735906602396;10868.945655271673;10940.835887461535 06:03:57 INFO - PROCESS | 1820 | |3;mootools.html;1391.9860801391985;1397.727443148585;1528.0907859823405;1730.9048002359866;1765.3997640802124;7632.809179770508;8489.19352661497;8627.396082274243;8650.740477785677;8806.955965220164;8427.747167584967;8660.133986601337;8680.8697869532;8710.477371357723;8716.302695784338;2332.1837356925066;2371.95256094878;2376.1089591403224;2376.3583832365252;2379.4646204603914;2049.5081180516645;2348.3542025942834;2382.1662418153683;2386.4630458146867;2390.055927908475;1980.3464856597288;2342.4846533762543;2347.9647805282934;2360.409897525619;2363.822713296501;2305.0664480885266;2332.3352844439337;2338.275134708246;2338.4738433852417;2346.979064107119;2322.7212734471805;2323.3726893738767;2329.126577533425;2329.953400931972;2330.4290448839974;8561.529115898624;8597.613107410181;8688.348373871922;8694;8715.69495067676;5198.428172901015;5355.518003379714;5361.463853614608;5363.436839131874;5384.434634363374;3323.7673362864607;3460.757746957714;3493.930121397583;3495.667911548399;3499.1077275294892;989.2926557511414;1006.1146191351564;1010.3382284603597;1011.4083261292153;1013.4426065663929;11490.310581365145;11580.363080030595;11611.593594224247;11615.186936914419;11618.419079046098;4524.434445694288;4526.008556235246;4556.65825063115;4601.861944141681;4617.0996655651825;9190.540472976325;9192.17270445663;9193.126652967958;9194.264458843276;9203.447793132433;5219.973900130475;5227.869303267373;5228.137357335997;5229.47705229474;5245.659032162974;3720.6279372062577;4388.5611438856495;4472.217361961643;4505.504394516666;4777.331173635694;3697.205100903319;3768.8115594220726;3843.577206507282;3853.6917046636204;3854.9614503855164;1117.3631030312806;1129.9152563557766;1131.5191043806349;1136.9488373023235;1138.3056335635256;719.5430901377558;728.6611725547646;876.4522173641474;906.8231694819578;949.9265829612493;4359.38968544404;4856.4415092264135;5461.535769459561;5669.518090962232;5700.486956173964;934.1779234273931;947.2895328503622;952.3761935931975;974.9220062394992;977.3354119199013;3752.493413389212;3895.8636447724193;3903.375459926455;3910.687145028391;4016.979915100406;8901.777455563662;10278.383297002203;10371.636992705218;10481.266311358284;10485.951404859605;10356.326838755456;10422.739431514121;10457.26799124054;10468.424236667057;10492.527836247235;7736;7954.045514538292;8119.106898241189;8165.591720413956;8231.34149268057;5709.68596727184;5719.428057194247;5808.36108028125;5879.235699359056;5895.29256489224;9495.28785341116;9672.08115229052;9863.112319891245;9895.158911492605;9900.504974751379;9477.620895164117;9930.851037234592;9955.601775928882;10075.143612792996;10094.14199793026;6772.898406523807;6976.302369763186;7136.500444968802;7333.889991650023;7345.192028876926;8166.265036146775;8188.7952801180445;8246.876296855671;8251.504909705196;8266.090730019814;6430.003349480838;7191.137063552407;7331.706731730671;7406.481546291708;7416.4066874648815;7022.297770222936;7200.351968322876;7230.13238411394;7236.638168091679;7294.416446684147;6602.273749887605;6675.566088204251;6691.799246022627;6692.263850976291;6694.531382803157;4668.393108895958;4727.905441891212;4758.262469317261;4831.758412079312;4962.106820772296;4190.832366705419;4270.231358355526;4694.2254528002495;4809.807607695653;5116.8720781979255 06:03:57 INFO - PROCESS | 1820 | |4;prototype.html;284.11639800221315;300.9262730630996;308.26940151840137;319.89283589997336;321.61406312425066;4627.8611641650805;4724.551167639073;4885.511448855117;5276.947230527693;5287.673423693582;4364.476262848462;4414.62475689566;5231.253591864273;5252.763625636846;5299.231611416342;3250.203700093469;3328.984659678797;3729.832157552903;3996.0609256824623;4067.9389809152885;3076.077176846948;3375.0087448405434;3791.924161516768;3967.107400834818;3981.9203615927663;3629.5825980012373;3747.7376583639157;3847.6344747249104;4025.5773143819915;4036;3390.542276792626;3760.229153023637;3931.783751893659;3994.560598334181;4018.457508236409;3482.686558209748;3498.915336245772;3503.562054743157;3503.9824800876086;3507.456344266617;5012.248162775576;5018.673786203885;5027.371578552681;5033.194688849767;5063.443021267658;3848.84534639607;4380.300550513311;4542.545745425464;4600.6549508786975;4608.769561521944;5477.780888764445;5503.605931419846;5508.7521061552325;5525.392206857243;5533.363663178746;3401.9829900850586;3848.903777405559;3871.4192871069286;3880.7477513961503;3897.707671924617;4963.081829861487;5530.004599172187;5845.327787304429;5856.941430585683;5887.205227294303;3700.629937006278;3898.688104951597;3939.6060393960947;3939.881803545898;3946.309395855714;3375.3249350130072;3679.7369617685467;3957.881263562098;4002.9199416011515;4004.279229738617;3540.070661489443;4343.609075183249;4538.709435659187;4589.449266088091;4604.677672562955;5199.558037566773;5600.85997850057;5615.971920140373;5636.210930469738;5665.951798917131;1156.6009726644295;1161.4831400027076;1187.655579881842;1284.9678758030936;1352.3441131051432;699.989500157498;800.6717245929141;807.7213361390311;819.9139090395422;832.6835802395051;4435.778211089432;4960.652754307236;4973.875653108701;4991.700497970133;5166.948330516722;5608.747606357723;5677.290338707661;5677.858053548612;5733.655980641175;5734.426557344315;4213.894652633709;4721.811127554928;4896.020795840846;5229.790808367698;5231.607629427808;2360.5836498101344;2973.3310005249077;2991.7905746597967;3000.8949686760857;3062.5865508156558;4837.830675926326;5508.76052888105;5830.53355731549;5942.494887934574;5996.400359963969;4823.855284341475;5575.358833734092;5807.593468457168;5820.592558520948;5844.6785426801935;5636;5894.528437725057;5912.881742365129;5914.704264786744;5959.493443057302;5294.708791016377;5636.351819540805;5682.261306030189;5683.857903552444;5688.317401911797;5559.221708960829;5604.8879022418505;5615.803446879339;5632.380438151718;5678.233438485751;5581.609287349846;5897.203877476486;5897.351291357862;5898.705064746831;5924.289085309791;3470.097774578577;3541.079319376929;3768.9623103768613;3778.678812300985;3778.962210377861;4710.92933606003;5234.659747116425;5469.972650136724;5476.698781567052;5742.28221472316;4703.119926060945;4804.543568361;5097.6941383517105;5244.580433565229;5559.388467268678;4673.929891051569;4989.700617962933;5344.984452953926;5475.72621368922;5531.285301556531;5008.248762685626;5072.916302376622;5377.220303056113;5487.670739755627;5502.9174562382395;4418.756968366771;4435.445569303837;5163.66436181647;5460.399356070909;5575.358833734011;4571.51999040096;4769.332293478847;5091.159958606789;5410.540104091197;5520.751566179451 06:03:57 INFO - PROCESS | 1820 | |5;yui.html;684.2712511175598;691.4779341597093;706.1349846438114;737.3584981066466;752.5559919647407;13442.39509222085;14845.851541484595;15265.542033738979;15273.92363038186;15291.388344466222;13964.860351396508;14030.43878244869;14035.228062456561;14071.155730656168;15194.772078418833;897.2014906733012;919.8942121656029;922.2667978956746;923.8891333040044;924.16824857628;902.5487256371814;915.3135148638521;917.8118485710447;918.9632414703403;919.9816003679922;968.8110818390417;972.9367591106555;973.226285103341;973.6154219083458;974.6637410093506;963.3449254506933;964.3201542912265;965.4593427680521;965.5220665770419;967.8451447768393;717.4726575966661;717.5228473065405;718.3427164144802;718.3534818663193;719.3382088478612;15063.774043389358;15076.623084422867;15083.321250543751;15117.31972061249;15137.394504219817;11918.940405297919;11988.460519276654;12119.818202726967;12262.490678598062;12496.50013999448;10608.734781630445;10903.34579925207;10970.067544258747;10975.451227438676;11373.033292170176;3900.7074469414906;3970.7617542947514;3988.78061706606;3992.5807790182193;4269.615734583902;72968;73723.89414158792;76956.61521692411;80472.59763701202;81370;7891.723789667449;9243.953780231055;9273.258139348836;9288.582013809395;9462.479563623929;20465.2837150699;20607.896960515103;20658.690119648218;20756.481087972923;20981;7062.3996960258955;7154.821129471805;7249.927500724925;7298.051253337032;7480.438967077491;3865.43951127085;3962.366021436614;3970.1861118470642;4045.8988525287104;4269.5090064642345;3400.472926696366;3534.6642069003406;3538.1862171700654;3540.309639620301;3546.716262698978;617.5059952038351;643.8454770854961;650.1288273713152;660.0824853453703;663.5288944849211;483.3547214468653;519.9714015729096;532.8055259830134;540.0899484368852;578.1674388880083;7667.348275396542;7708.344790692742;7712.074551053797;7734.1105772836845;7785.221477852169;556.3129535024242;584.4652143288928;591.2432086928738;598.775459202799;625.8779537990048;3492.2666240089866;3649.251903359805;3653.50677658518;3798.240351929625;3889.0471834400187;91792;94075.059249408;96484;97099;97112;67525.63660271867;75916.24083759091;84338.73491897626;84730;84997.15002850017;75276;75416.62291688507;75635.24364756282;76209.85685214726;77389.61305193439;7111.608861512666;7172.748953786592;7205.927940720526;7208.675609597685;7244.637768111468;33598.304186538975;45663.77168114271;46221.30668040049;46753.53246467628;46968.530314696414;63822.68088659527;63975.68012159909;64004.67997659982;64153;65672;10047;10126.59493620247;10358.533865975898;10371.792564148524;10397.32417392867;10783.514741836792;10930.89805160854;10969.451527423438;10998.835017474585;12096.455659495168;9602.66390676323;9731.172850307803;10058.698239052841;10561.049505544537;10569.101626361846;10610.628627997983;10683.94658026705;10864.782704345711;10872.61945831892;11010.50452729613;9677.70966870995;9750.561224744763;9833.60665573369;9894.158996485092;9983.900160998297;7631.38948884099;7887.802804929693;8668.003179634423;8808.779780505538;8947.552622368725;8425.620847061775;8536.530490823065;8538.743837684879;8604.61279242423;8786.968495868916 06:03:57 INFO - PROCESS | 1820 | __end_tp_report 06:03:57 INFO - PROCESS | 1820 | __start_cc_report 06:03:57 INFO - PROCESS | 1820 | _x_x_mozilla_cycle_collect,1450 06:03:57 INFO - PROCESS | 1820 | __end_cc_report 06:03:57 INFO - PROCESS | 1820 | __startTimestamp1464267837273__endTimestamp 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | ------- Summary: start ------- 06:03:57 INFO - PROCESS | 1820 | Number of tests: 6 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | [#0] dojo.html Cycles:165 Average:5697.91 Median:5888.15 stddev:642.11 (10.9%) stddev-sans-first:607.28 06:03:57 INFO - PROCESS | 1820 | Values: 2966.5 3467.4 3507.0 3524.8 3559.0 5566.2 5614.4 5631.6 5703.1 5715.4 5614.7 5669.7 5677.4 5682.4 5683.1 5084.2 5136.9 5156.1 5178.2 5214.2 5240.4 5283.2 5286.9 5289.1 5293.1 4353.4 5162.0 5175.9 5193.8 5279.7 5272.8 5299.0 5302.0 5302.5 5364.2 5055.7 5070.2 5079.6 5204.8 5211.6 5504.1 5560.6 5587.0 5587.9 5588.1 6197.6 6207.6 6230.6 6232.1 6238.2 5835.7 6198.9 6256.6 6266.6 6289.5 5783.0 5802.7 5819.2 5819.7 5823.2 6287.3 6330.2 6336.0 6336.5 6351.1 5943.2 6019.2 6038.1 6094.1 6150.2 6163.2 6209.5 6211.2 6219.2 6222.9 6091.7 6113.2 6119.3 6139.9 6172.0 6186.1 6209.3 6233.7 6234.1 6234.2 4876.8 4881.0 4887.4 4888.6 4918.5 4326.5 4398.4 4434.9 4466.0 4472.6 4820.5 5626.3 5699.8 5710.4 5733.7 6237.3 6252.1 6264.9 6268.8 6270.7 5601.9 5706.5 5732.4 5736.7 5762.9 4573.2 5196.7 5239.3 5247.8 5375.5 5519.8 6068.8 6235.0 6247.5 6265.4 6115.9 6156.1 6156.4 6158.3 6171.8 6173.9 6220.0 6223.7 6259.2 6264.9 6198.5 6203.2 6206.6 6219.6 6231.8 6245.1 6252.1 6257.2 6266.1 6275.0 5886.2 5959.2 6002.2 6079.1 6113.3 6036.0 6046.8 6090.9 6114.6 6139.1 5807.5 5881.7 5896.8 5927.8 5966.3 5911.4 5932.3 5956.8 6121.4 6134.2 5831.3 5863.3 5888.1 5892.1 5930.1 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | [#1] ext.html Cycles:180 Average:67855.70 Median:34758.98 stddev:150951.14 (434.3%) stddev-sans-first:151289.86 06:03:57 INFO - PROCESS | 1820 | Values: 488.8 499.7 506.2 509.7 513.7 30331.8 41854.0 43273.8 43316.4 45708.3 44272.0 44592.0 44597.3 44702.6 44812.6 8538.4 9486.5 9720.5 9738.8 9743.4 8523.2 11477.0 11614.5 11692.9 11961.5 11576.9 11627.0 11631.7 11640.5 11655.2 8600.6 11143.9 11519.7 11747.7 11835.8 9823.7 9907.4 11698.4 11770.1 11805.3 41166.0 41531.8 41614.4 41619.4 41878.2 3130.2 3152.7 3199.4 3707.9 3994.3 28816.0 29455.1 29895.0 30127.7 30585.4 2590.4 2700.5 2718.1 2737.0 2769.3 513285.0 515088.0 515784.4 516063.0 517243.4 34404.5 35113.5 60766.0 62355.0 62378.1 36122.0 36257.6 36372.1 36403.0 36421.1 15224.5 15357.6 15380.9 15409.5 15414.7 10868.1 11820.9 12163.5 12774.9 13011.7 7886.1 7896.4 7904.1 7964.6 7972.3 3273.4 3289.2 3292.9 3296.6 3318.3 937.7 1108.6 1121.0 1131.9 1177.6 29007.0 29145.3 29290.6 29385.9 29677.4 848.3 1050.3 1082.0 1158.1 1185.8 14923.2 14985.6 15239.8 15332.5 15386.8 597101.0 698034.0 702089.0 703060.5 703789.0 61408.0 61580.4 61836.4 61890.7 62131.4 1318.3 1343.4 1366.9 1463.0 1482.2 24417.9 30072.2 30296.1 30507.5 30530.5 51227.5 51417.2 51782.7 51802.2 51999.2 470223.0 470562.0 471247.0 471444.0 471899.0 22825.5 23185.5 25669.0 25807.6 26469.5 46238.8 46462.3 46734.0 47627.5 47773.3 32853.7 35334.0 38398.4 38619.0 38934.8 37182.3 37222.0 37613.6 38054.4 38703.2 28931.4 37965.0 38079.0 38266.0 38432.8 32452.4 32881.8 33528.5 33729.5 34114.8 24923.9 25011.0 25128.7 25370.1 25579.2 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | [#2] jquery.html Cycles:180 Average:33262.81 Median:2097.83 stddev:95829.10 (4568.0%) stddev-sans-first:96066.12 06:03:57 INFO - PROCESS | 1820 | Values: 381.3 382.4 386.2 399.3 412.7 20072.0 21674.9 22078.7 22082.6 22233.9 20457.4 20791.8 20814.7 21468.9 21482.6 5170.7 5851.0 5936.4 5959.9 5971.7 4140.4 4375.6 5939.3 6299.2 6660.9 6521.5 6620.5 6621.4 6624.6 6626.8 6418.1 6623.3 6639.1 6659.3 6674.4 3700.9 3814.3 4073.6 5711.9 5721.9 25930.5 26512.7 26823.7 26925.1 27034.6 1826.9 1966.7 2021.0 2037.7 2270.3 10113.4 10385.8 10409.9 15104.7 15489.6 1663.9 1675.1 1717.3 1742.5 1758.1 79268.2 79371.2 82967.0 114476.4 120237.0 11366.7 11468.7 11669.8 11709.5 11820.1 14674.6 14756.3 14792.6 14805.8 14806.3 8470.5 8640.4 8681.7 8795.9 8817.9 8587.7 8588.8 8605.4 8720.4 8763.7 4532.5 4614.7 4638.7 4654.3 4697.7 1747.6 1758.4 1826.3 1909.7 1988.5 873.5 906.3 929.4 935.1 994.5 19553.8 19792.6 20218.5 20264.0 20663.3 881.7 903.1 924.1 932.9 987.3 8980.4 9902.6 10554.9 10758.7 10801.1 547647.0 580687.0 583299.0 586568.0 588529.0 91871.4 93090.0 109820.0 130242.0 140317.3 953.2 1031.8 1041.8 1094.5 1098.5 17621.3 17716.8 18002.4 18282.0 18415.8 47179.0 51170.7 51515.0 51627.7 52107.7 75210.0 75852.0 76814.2 77735.0 77769.2 5477.5 5891.5 7343.9 7569.9 7690.6 8972.9 11943.3 12131.1 12229.6 12326.9 10289.8 10486.4 10531.8 10671.1 10808.2 10202.7 10263.4 10450.7 10707.0 10843.5 10492.6 10827.6 11047.3 11098.9 11173.0 7832.7 7893.8 7978.3 8056.8 8101.0 7897.3 9517.0 10563.7 10868.9 10940.8 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | [#3] mootools.html Cycles:180 Average:5536.37 Median:5039.49 stddev:3118.62 (61.9%) stddev-sans-first:3111.82 06:03:57 INFO - PROCESS | 1820 | Values: 1392.0 1397.7 1528.1 1730.9 1765.4 7632.8 8489.2 8627.4 8650.7 8807.0 8427.7 8660.1 8680.9 8710.5 8716.3 2332.2 2372.0 2376.1 2376.4 2379.5 2049.5 2348.4 2382.2 2386.5 2390.1 1980.3 2342.5 2348.0 2360.4 2363.8 2305.1 2332.3 2338.3 2338.5 2347.0 2322.7 2323.4 2329.1 2330.0 2330.4 8561.5 8597.6 8688.3 8694.0 8715.7 5198.4 5355.5 5361.5 5363.4 5384.4 3323.8 3460.8 3493.9 3495.7 3499.1 989.3 1006.1 1010.3 1011.4 1013.4 11490.3 11580.4 11611.6 11615.2 11618.4 4524.4 4526.0 4556.7 4601.9 4617.1 9190.5 9192.2 9193.1 9194.3 9203.4 5220.0 5227.9 5228.1 5229.5 5245.7 3720.6 4388.6 4472.2 4505.5 4777.3 3697.2 3768.8 3843.6 3853.7 3855.0 1117.4 1129.9 1131.5 1136.9 1138.3 719.5 728.7 876.5 906.8 949.9 4359.4 4856.4 5461.5 5669.5 5700.5 934.2 947.3 952.4 974.9 977.3 3752.5 3895.9 3903.4 3910.7 4017.0 8901.8 10278.4 10371.6 10481.3 10486.0 10356.3 10422.7 10457.3 10468.4 10492.5 7736.0 7954.0 8119.1 8165.6 8231.3 5709.7 5719.4 5808.4 5879.2 5895.3 9495.3 9672.1 9863.1 9895.2 9900.5 9477.6 9930.9 9955.6 10075.1 10094.1 6772.9 6976.3 7136.5 7333.9 7345.2 8166.3 8188.8 8246.9 8251.5 8266.1 6430.0 7191.1 7331.7 7406.5 7416.4 7022.3 7200.4 7230.1 7236.6 7294.4 6602.3 6675.6 6691.8 6692.3 6694.5 4668.4 4727.9 4758.3 4831.8 4962.1 4190.8 4270.2 4694.2 4809.8 5116.9 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | [#4] prototype.html Cycles:180 Average:4435.46 Median:4968.48 stddev:1392.46 (28.0%) stddev-sans-first:1361.05 06:03:57 INFO - PROCESS | 1820 | Values: 284.1 300.9 308.3 319.9 321.6 4627.9 4724.6 4885.5 5276.9 5287.7 4364.5 4414.6 5231.3 5252.8 5299.2 3250.2 3329.0 3729.8 3996.1 4067.9 3076.1 3375.0 3791.9 3967.1 3981.9 3629.6 3747.7 3847.6 4025.6 4036.0 3390.5 3760.2 3931.8 3994.6 4018.5 3482.7 3498.9 3503.6 3504.0 3507.5 5012.2 5018.7 5027.4 5033.2 5063.4 3848.8 4380.3 4542.5 4600.7 4608.8 5477.8 5503.6 5508.8 5525.4 5533.4 3402.0 3848.9 3871.4 3880.7 3897.7 4963.1 5530.0 5845.3 5856.9 5887.2 3700.6 3898.7 3939.6 3939.9 3946.3 3375.3 3679.7 3957.9 4002.9 4004.3 3540.1 4343.6 4538.7 4589.4 4604.7 5199.6 5600.9 5616.0 5636.2 5666.0 1156.6 1161.5 1187.7 1285.0 1352.3 700.0 800.7 807.7 819.9 832.7 4435.8 4960.7 4973.9 4991.7 5166.9 5608.7 5677.3 5677.9 5733.7 5734.4 4213.9 4721.8 4896.0 5229.8 5231.6 2360.6 2973.3 2991.8 3000.9 3062.6 4837.8 5508.8 5830.5 5942.5 5996.4 4823.9 5575.4 5807.6 5820.6 5844.7 5636.0 5894.5 5912.9 5914.7 5959.5 5294.7 5636.4 5682.3 5683.9 5688.3 5559.2 5604.9 5615.8 5632.4 5678.2 5581.6 5897.2 5897.4 5898.7 5924.3 3470.1 3541.1 3769.0 3778.7 3779.0 4710.9 5234.7 5470.0 5476.7 5742.3 4703.1 4804.5 5097.7 5244.6 5559.4 4673.9 4989.7 5345.0 5475.7 5531.3 5008.2 5072.9 5377.2 5487.7 5502.9 4418.8 4435.4 5163.7 5460.4 5575.4 4571.5 4769.3 5091.2 5410.5 5520.8 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - PROCESS | 1820 | [#5] yui.html Cycles:180 Average:17961.22 Median:33166.04 stddev:25896.06 (78.1%) stddev-sans-first:25936.21 06:03:57 INFO - PROCESS | 1820 | Values: 684.3 691.5 706.1 737.4 752.6 13442.4 14845.9 15265.5 15273.9 15291.4 13964.9 14030.4 14035.2 14071.2 15194.8 897.2 919.9 922.3 923.9 924.2 902.5 915.3 917.8 919.0 920.0 968.8 972.9 973.2 973.6 974.7 963.3 964.3 965.5 965.5 967.8 717.5 717.5 718.3 718.4 719.3 15063.8 15076.6 15083.3 15117.3 15137.4 11918.9 11988.5 12119.8 12262.5 12496.5 10608.7 10903.3 10970.1 10975.5 11373.0 3900.7 3970.8 3988.8 3992.6 4269.6 72968.0 73723.9 76956.6 80472.6 81370.0 7891.7 9244.0 9273.3 9288.6 9462.5 20465.3 20607.9 20658.7 20756.5 20981.0 7062.4 7154.8 7249.9 7298.1 7480.4 3865.4 3962.4 3970.2 4045.9 4269.5 3400.5 3534.7 3538.2 3540.3 3546.7 617.5 643.8 650.1 660.1 663.5 483.4 520.0 532.8 540.1 578.2 7667.3 7708.3 7712.1 7734.1 7785.2 556.3 584.5 591.2 598.8 625.9 3492.3 3649.3 3653.5 3798.2 3889.0 91792.0 94075.1 96484.0 97099.0 97112.0 67525.6 75916.2 84338.7 84730.0 84997.2 75276.0 75416.6 75635.2 76209.9 77389.6 7111.6 7172.7 7205.9 7208.7 7244.6 33598.3 45663.8 46221.3 46753.5 46968.5 63822.7 63975.7 64004.7 64153.0 65672.0 10047.0 10126.6 10358.5 10371.8 10397.3 10783.5 10930.9 10969.5 10998.8 12096.5 9602.7 9731.2 10058.7 10561.0 10569.1 10610.6 10683.9 10864.8 10872.6 11010.5 9677.7 9750.6 9833.6 9894.2 9983.9 7631.4 7887.8 8668.0 8808.8 8947.6 8425.6 8536.5 8538.7 8604.6 8787.0 06:03:57 INFO - PROCESS | 1820 | -------- Summary: end -------- 06:03:57 INFO - PROCESS | 1820 | 06:03:57 INFO - TEST-INFO | 1820: exit 0 06:03:57 INFO - TEST-OK | dromaeo_css | took 1116787ms 06:03:57 INFO - TEST-START | kraken 06:03:57 INFO - operating with platform_type : mac_ 06:03:57 INFO - Initialising browser for kraken test... 06:03:57 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmp7SToGx/profile http://localhost:49275/getInfo.html) 06:04:00 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 06:04:00 INFO - PROCESS | 1939 | colorDepth:24 06:04:00 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 06:04:00 INFO - PROCESS | 1939 | __metrics 06:04:00 INFO - PROCESS | 1939 | 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" 06:04:00 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:04:00 INFO - TEST-INFO | 1939: exit 0 06:04:00 INFO - Browser initialized. 06:04:00 INFO - Running cycle 1/1 for kraken test... 06:04:00 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmp7SToGx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:12 INFO - PROCESS | 1940 | RSS: Main: 241016832 06:04:12 INFO - PROCESS | 1940 | 06:04:15 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:04:16 INFO - PROCESS | 1940 | RSS: Main: 289660928 06:04:16 INFO - PROCESS | 1940 | 06:04:18 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:04:19 INFO - PROCESS | 1940 | RSS: Main: 299102208 06:04:19 INFO - PROCESS | 1940 | 06:04:23 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:04:23 INFO - PROCESS | 1940 | RSS: Main: 390172672 06:04:23 INFO - PROCESS | 1940 | 06:04:25 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:04:26 INFO - PROCESS | 1940 | RSS: Main: 288796672 06:04:26 INFO - PROCESS | 1940 | 06:04:28 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:04:29 INFO - PROCESS | 1940 | RSS: Main: 293519360 06:04:29 INFO - PROCESS | 1940 | 06:04:31 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:04:32 INFO - PROCESS | 1940 | RSS: Main: 331440128 06:04:32 INFO - PROCESS | 1940 | 06:04:35 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:04:36 INFO - PROCESS | 1940 | RSS: Main: 331141120 06:04:36 INFO - PROCESS | 1940 | 06:04:38 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:04:39 INFO - PROCESS | 1940 | RSS: Main: 330280960 06:04:39 INFO - PROCESS | 1940 | 06:04:40 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:04:41 INFO - PROCESS | 1940 | RSS: Main: 317173760 06:04:41 INFO - PROCESS | 1940 | 06:04:43 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:04:44 INFO - PROCESS | 1940 | RSS: Main: 324259840 06:04:44 INFO - PROCESS | 1940 | 06:04:46 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:04:46 INFO - PROCESS | 1940 | RSS: Main: 326127616 06:04:46 INFO - PROCESS | 1940 | 06:04:49 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:04:50 INFO - PROCESS | 1940 | RSS: Main: 330551296 06:04:50 INFO - PROCESS | 1940 | 06:04:53 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:04:54 INFO - PROCESS | 1940 | RSS: Main: 328810496 06:04:54 INFO - PROCESS | 1940 | 06:04:55 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:04:56 INFO - PROCESS | 1940 | RSS: Main: 375918592 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | __start_tp_report 06:04:56 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 06:04:56 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 06:04:56 INFO - PROCESS | 1940 | |i|pagename|runs| 06:04:56 INFO - PROCESS | 1940 | |0;ai-astar;112;107;110;112;112;112;113;108;112;111 06:04:56 INFO - PROCESS | 1940 | |1;audio-beat-detection;151;117;115;167;117;116;138;116;154;136 06:04:56 INFO - PROCESS | 1940 | |2;audio-dft;208;261;200;183;183;179;183;183;183;184 06:04:56 INFO - PROCESS | 1940 | |3;audio-fft;79;58;60;135;56;57;83;88;81;55 06:04:56 INFO - PROCESS | 1940 | |4;audio-oscillator;91;69;102;71;71;69;97;71;68;99 06:04:56 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;87;84;86;84;87;83;86;83;85;82 06:04:56 INFO - PROCESS | 1940 | |6;imaging-darkroom;140;108;97;92;93;92;93;92;93;93 06:04:56 INFO - PROCESS | 1940 | |7;imaging-desaturate;85;86;86;85;84;84;87;86;84;84 06:04:56 INFO - PROCESS | 1940 | |8;json-parse-financial;57;55;57;57;58;57;57;73;57;55 06:04:56 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;66;67;73;77;74;71;70;70;71;71 06:04:56 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;86;85;87;87;86;86;85;86;87;88 06:04:56 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;141;140;149;151;140;135;141;142;182;141 06:04:56 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;207;227;184;163;193;222;176;173;166;173 06:04:56 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;68;67;67;67;67;65;66;66;66;68 06:04:56 INFO - PROCESS | 1940 | __end_tp_report 06:04:56 INFO - PROCESS | 1940 | __start_cc_report 06:04:56 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1966 06:04:56 INFO - PROCESS | 1940 | __end_cc_report 06:04:56 INFO - PROCESS | 1940 | __startTimestamp1464267896053__endTimestamp 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | ------- Summary: start ------- 06:04:56 INFO - PROCESS | 1940 | Number of tests: 14 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:1.97 (1.8%) stddev-sans-first:2.05 06:04:56 INFO - PROCESS | 1940 | Values: 112.0 107.0 110.0 112.0 112.0 112.0 113.0 108.0 112.0 111.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:137.00 stddev:19.35 (14.1%) stddev-sans-first:19.35 06:04:56 INFO - PROCESS | 1940 | Values: 151.0 117.0 115.0 167.0 117.0 116.0 138.0 116.0 154.0 136.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:194.70 Median:183.50 stddev:25.05 (13.6%) stddev-sans-first:26.10 06:04:56 INFO - PROCESS | 1940 | Values: 208.0 261.0 200.0 183.0 183.0 179.0 183.0 183.0 183.0 184.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:75.20 Median:69.50 stddev:24.68 (35.5%) stddev-sans-first:26.14 06:04:56 INFO - PROCESS | 1940 | Values: 79.0 58.0 60.0 135.0 56.0 57.0 83.0 88.0 81.0 55.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:71.00 stddev:14.44 (20.3%) stddev-sans-first:14.84 06:04:56 INFO - PROCESS | 1940 | Values: 91.0 69.0 102.0 71.0 71.0 69.0 97.0 71.0 68.0 99.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:85.50 stddev:1.77 (2.1%) stddev-sans-first:1.67 06:04:56 INFO - PROCESS | 1940 | Values: 87.0 84.0 86.0 84.0 87.0 83.0 86.0 83.0 85.0 82.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:99.30 Median:93.00 stddev:15.11 (16.3%) stddev-sans-first:5.19 06:04:56 INFO - PROCESS | 1940 | Values: 140.0 108.0 97.0 92.0 93.0 92.0 93.0 92.0 93.0 93.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.10 (1.3%) stddev-sans-first:1.17 06:04:56 INFO - PROCESS | 1940 | Values: 85.0 86.0 86.0 85.0 84.0 84.0 87.0 86.0 84.0 84.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:57.00 stddev:5.25 (9.2%) stddev-sans-first:5.55 06:04:56 INFO - PROCESS | 1940 | Values: 57.0 55.0 57.0 57.0 58.0 57.0 57.0 73.0 57.0 55.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.20 (4.5%) stddev-sans-first:2.83 06:04:56 INFO - PROCESS | 1940 | Values: 66.0 67.0 73.0 77.0 74.0 71.0 70.0 70.0 71.0 71.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.50 stddev:0.95 (1.1%) stddev-sans-first:1.00 06:04:56 INFO - PROCESS | 1940 | Values: 86.0 85.0 87.0 87.0 86.0 86.0 85.0 86.0 87.0 88.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:141.50 stddev:13.39 (9.5%) stddev-sans-first:14.07 06:04:56 INFO - PROCESS | 1940 | Values: 141.0 140.0 149.0 151.0 140.0 135.0 141.0 142.0 182.0 141.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.40 Median:188.50 stddev:23.05 (12.2%) stddev-sans-first:23.44 06:04:56 INFO - PROCESS | 1940 | Values: 207.0 227.0 184.0 163.0 193.0 222.0 176.0 173.0 166.0 173.0 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:0.88 06:04:56 INFO - PROCESS | 1940 | Values: 68.0 67.0 67.0 67.0 67.0 65.0 66.0 66.0 66.0 68.0 06:04:56 INFO - PROCESS | 1940 | -------- Summary: end -------- 06:04:56 INFO - PROCESS | 1940 | 06:04:56 INFO - TEST-INFO | 1940: exit 0 06:04:56 INFO - TEST-OK | kraken | took 59048ms 06:04:56 INFO - SUITE-END | took 1175s 06:04:57 INFO - Completed test suite (00:19:36) 06:04:57 INFO - javascript benchmark 06:04:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2966.4956957317254, 3467.358538670346, 3506.964930350697, 3524.770889892155, 3558.950109717633, 5566.2207290979195, 5614.410486898878, 5631.60578759487, 5703.0589952657765, 5715.399883012281, 5614.719264036802, 5669.688167150805, 5677.432256774331, 5682.403347648489, 5683.147527870821, 5084.211947148188, 5136.948630513703, 5156.097682905476, 5178.249153872686, 5214.243934629477, 5240.397354304247, 5283.181106928413, 5286.894262114755, 5289.074411977907, 5293.126634105368, 4353.390525326441, 5162.0, 5175.948240517603, 5193.766280517367, 5279.70961597112, 5272.841814745564, 5299.046171689094, 5302.0, 5302.496262855023, 5364.222187782789, 5055.74721263939, 5070.188769796851, 5079.568236699885, 5204.791808327663, 5211.5830733541225, 5504.146857237135, 5560.555155587544, 5587.0, 5587.8882422351735, 5588.133839254922, 6197.566170368076, 6207.6275423474735, 6230.59501132425, 6232.127502149703, 6238.220222472191, 5835.679037652927, 6198.907016394758, 6256.562040657156, 6266.624002559861, 6289.465395441393, 5783.045797443375, 5802.738876750556, 5819.185314055993, 5819.73811178498, 5823.213866128103, 6287.308396076428, 6330.240371155491, 6335.968320158462, 6336.524760642878, 6351.110844481776, 5943.197668314809, 6019.217501724747, 6038.064100064497, 6094.085887116879, 6150.231221097363, 6163.229596300463, 6209.5032397408095, 6211.223597050369, 6219.191505104308, 6222.875542489125, 6091.664958427329, 6113.202667687911, 6119.257281746883, 6139.8601398601395, 6171.9691401542705, 6186.10301506279, 6209.285932117864, 6233.688315584293, 6234.096056071935, 6234.1583886174785, 4876.829310974099, 4881.023795240895, 4887.3890763653835, 4888.560029597282, 4918.532739389752, 4326.4808223012815, 4398.406215160976, 4434.889127771831, 4466.0, 4472.575105364985, 4820.493848145964, 5626.268585083994, 5699.772009119672, 5710.428957104256, 5733.741981610838, 6237.251529816451, 6252.124702541648, 6264.937350626436, 6268.811935641938, 6270.7491700331475, 5601.943980560142, 5706.457886500776, 5732.398098199712, 5736.741846616912, 5762.9135562966585, 4573.153966516205, 5196.68819870809, 5239.345081864767, 5247.842564723064, 5375.5162035417, 5519.779208831602, 6068.75724971005, 6234.9688251559355, 6247.531435142473, 6265.404786545271, 6115.908261375994, 6156.076588511759, 6156.384361563808, 6158.291796443466, 6171.84570385744, 6173.938260617337, 6220.0047992321015, 6223.688815559113, 6259.186305780219, 6264.906026409516, 6198.535109866688, 6203.16257305258, 6206.565540412308, 6219.564630475823, 6231.781887633911, 6245.063240513778, 6252.062190671436, 6257.1552840367785, 6266.122742815918, 6274.968625156845, 5886.20536227621, 5959.1657167995645, 6002.249718785152, 6079.118527813531, 6113.266408031065, 6036.0, 6046.758129674764, 6090.9390906090375, 6114.60152118864, 6139.140520327069, 5807.506361959281, 5881.705914704333, 5896.823095307148, 5927.8221653350465, 5966.284045914518, 5911.438413350725, 5932.288125424977, 5956.821295361146, 6121.387861213843, 6134.171886795226, 5831.271091113611, 5863.296404431325, 5888.146218798336, 5892.057270836645, 5930.080837470199], "value": 5657.303801091924, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.836239859647, 499.6802046690118, 506.1875689518323, 509.6559822120068, 513.7277243061179, 30331.848340758293, 41854.0, 43273.78363108184, 43316.350254746125, 45708.3143752844, 44272.0, 44592.0, 44597.33104003435, 44702.55297447024, 44812.55187448124, 8538.44500107494, 9486.478243696594, 9720.513974301291, 9738.756531086745, 9743.366681165702, 8523.232909038185, 11477.024452921512, 11614.535418583246, 11692.941535292312, 11961.521539138424, 11576.942115289412, 11627.01170400517, 11631.6510504685, 11640.476178571984, 11655.242409243414, 8600.569971501462, 11143.944280278587, 11519.654410367702, 11747.7063073423, 11835.82246266311, 9823.705288841275, 9907.356021858628, 11698.41507924609, 11770.117241206857, 11805.291682498992, 41166.0, 41531.7923410384, 41614.375784363256, 41619.37570936408, 41878.162436751096, 3130.2174456385906, 3152.668969758165, 3199.42410366134, 3707.9073023174365, 3994.3409337459284, 28815.9914402997, 29455.11634650964, 29894.953676621215, 30127.69872301271, 30585.388292234253, 2590.3912580543556, 2700.5004074246326, 2718.0894400375896, 2736.987314693556, 2769.2938300733385, 513285.0, 515088.0, 515784.42107789597, 516063.0, 517243.41378292866, 34404.48393274103, 35113.47329790055, 60766.0, 62355.0, 62378.0643290351, 36122.0, 36257.63742362543, 36372.09069773277, 36403.0, 36421.08947276339, 15224.467143650141, 15357.6160595986, 15380.92309538445, 15409.537713868378, 15414.691706165813, 10868.130549556017, 11820.88179118215, 12163.452644630835, 12774.9361253195, 13011.739765204833, 7886.132525422199, 7896.407769417317, 7904.051513818264, 7964.641591128413, 7972.2824945754, 3273.378058168988, 3289.227031647561, 3292.9176770580925, 3296.5549650796834, 3318.303156337148, 937.685875231805, 1108.5510368300852, 1120.9663710088548, 1131.8868113188614, 1177.552530038597, 29007.0, 29145.271368215963, 29290.560641590393, 29385.85307073451, 29677.406451871273, 848.3298194426334, 1050.2910535388583, 1081.983770243447, 1158.1314014489133, 1185.7984142695761, 14923.179225142505, 14985.550433487013, 15239.847601524065, 15332.463363782213, 15386.769198462032, 597101.0, 698034.0, 702089.0, 703060.4846975732, 703789.0, 61408.0, 61580.384196157465, 61836.38163618396, 61890.69054654698, 62131.378686212556, 1318.2617734068951, 1343.3753304713355, 1366.9201330948638, 1462.9707405851823, 1482.2292407948023, 24417.877910610332, 30072.24819379533, 30296.09111726652, 30507.54238686377, 30530.542041869834, 51227.48772512376, 51417.22874156816, 51782.74108629433, 51802.22296665478, 51999.2200116991, 470223.03104235156, 470562.0, 471247.0, 471444.0, 471899.0, 22825.543489130458, 23185.536289274456, 25669.0, 25807.580583068113, 26469.470610588065, 46238.76880615576, 46462.30306545337, 46734.0, 47627.52372476231, 47773.28340074832, 32853.67146328506, 35334.0, 38398.42402363911, 38619.03452413712, 38934.80532597319, 37182.256354873294, 37222.0, 37613.623863761015, 38054.445740377036, 38703.22593548057, 28931.421371572873, 37965.0, 38079.04802379962, 38266.0433489165, 38432.807835960644, 32452.350952980334, 32881.835590821895, 33528.49707254345, 33729.494057588665, 34114.82942585271, 24923.87538062298, 25011.0, 25128.74863787696, 25370.1120460783, 25579.232623020594], "value": 18820.14253847318, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.3364745343102, 382.4187235402188, 386.24103636354556, 399.2613664720266, 412.6987299271532, 20072.0, 21674.891625541888, 22078.668819967694, 22082.558348833034, 22233.888830555825, 20457.386278411635, 20791.792082079173, 20814.687779683318, 21468.926553672292, 21482.570348593017, 5170.715610641413, 5850.97074514629, 5936.376680448556, 5959.940400596003, 5971.731272092767, 4140.378943158536, 4375.628071613916, 5939.2575928009, 6299.244090709121, 6660.866782664344, 6521.543491955565, 6620.470362371023, 6621.37096975789, 6624.635645039521, 6626.768063117792, 6418.06937993993, 6623.337666233362, 6639.103720997652, 6659.33406659335, 6674.365935236145, 3700.9259814803822, 3814.332491813949, 4073.551909289976, 5711.857203569944, 5721.914171287434, 25930.481390372086, 26512.73487265122, 26823.731762682317, 26925.057622983102, 27034.5944810828, 1826.9086545672662, 1966.6853303471376, 2021.0, 2037.7045328427444, 2270.3075561953597, 10113.443760593165, 10385.758158348166, 10409.9479502602, 15104.697906041818, 15489.612759680986, 1663.9168041597993, 1675.08707754273, 1717.2959086774363, 1742.538227369748, 1758.0506526475688, 79268.20731792666, 79371.23384864425, 82966.9999999994, 114476.42761786221, 120237.0, 11366.659000230007, 11468.655940321803, 11669.76660466803, 11709.473073711704, 11820.113491488, 14674.55976320712, 14756.335964881391, 14792.63018424548, 14805.777913331309, 14806.259687015607, 8470.534120623302, 8640.395172338001, 8681.696140635173, 8795.912040879637, 8817.867731983897, 8587.699430519902, 8588.785280368042, 8605.440646357967, 8720.389572729975, 8763.693270735494, 4532.524084971096, 4614.653900957442, 4638.6752927294765, 4654.301854721751, 4697.694649847749, 1747.560854257788, 1758.384565402099, 1826.278619945141, 1909.6849019911838, 1988.5227545389002, 873.5021038008401, 906.2795077913075, 929.4423345992351, 935.0836180543106, 994.5126887825058, 19553.804461955482, 19792.60414791696, 20218.494537636383, 20264.0, 20663.276785312442, 881.7090360181124, 903.1239697493421, 924.075924075924, 932.8554074118523, 987.3138168972495, 8980.416272942239, 9902.55438505269, 10554.894451055392, 10758.731031724114, 10801.135909127252, 547647.0, 580687.0, 583299.0, 586568.0, 588529.0, 91871.43680101643, 93090.0, 109820.0, 130242.0, 140317.2984135073, 953.2278854128178, 1031.83490641497, 1041.7864337810731, 1094.4637127807475, 1098.5386137822134, 17621.295148193927, 17716.82283177203, 18002.36991705284, 18281.99449030316, 18415.815841841944, 47179.0, 51170.74414627903, 51515.0, 51627.74186129045, 52107.697307567614, 75210.0, 75852.0, 76814.2318576807, 77735.0, 77769.2223077762, 5477.507024367826, 5891.469767720926, 7343.898415237757, 7569.886451703119, 7690.577018264049, 8972.865407019031, 11943.283402995849, 12131.090168237593, 12229.633111006684, 12326.886963070856, 10289.794204115726, 10486.423246721504, 10531.842022369518, 10671.146308295163, 10808.18938579594, 10202.693919182437, 10263.435511046666, 10450.738731531772, 10706.982836630508, 10843.45782710877, 10492.580296788043, 10827.621033263798, 11047.337159770439, 11098.945600167972, 11172.994430501294, 7832.686692532463, 7893.84212315739, 7978.281954624112, 8056.758297251092, 8100.959495202486, 7897.328727058264, 9516.95241523788, 10563.735906602396, 10868.945655271673, 10940.835887461535], "value": 9340.62717998868, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.9860801391985, 1397.727443148585, 1528.0907859823405, 1730.9048002359866, 1765.3997640802124, 7632.809179770508, 8489.19352661497, 8627.396082274243, 8650.740477785677, 8806.955965220164, 8427.747167584967, 8660.133986601337, 8680.8697869532, 8710.477371357723, 8716.302695784338, 2332.1837356925066, 2371.95256094878, 2376.1089591403224, 2376.3583832365252, 2379.4646204603914, 2049.5081180516645, 2348.3542025942834, 2382.1662418153683, 2386.4630458146867, 2390.055927908475, 1980.3464856597288, 2342.4846533762543, 2347.9647805282934, 2360.409897525619, 2363.822713296501, 2305.0664480885266, 2332.3352844439337, 2338.275134708246, 2338.4738433852417, 2346.979064107119, 2322.7212734471805, 2323.3726893738767, 2329.126577533425, 2329.953400931972, 2330.4290448839974, 8561.529115898624, 8597.613107410181, 8688.348373871922, 8694.0, 8715.69495067676, 5198.428172901015, 5355.518003379714, 5361.463853614608, 5363.436839131874, 5384.434634363374, 3323.7673362864607, 3460.757746957714, 3493.930121397583, 3495.667911548399, 3499.1077275294892, 989.2926557511414, 1006.1146191351564, 1010.3382284603597, 1011.4083261292153, 1013.4426065663929, 11490.310581365145, 11580.363080030595, 11611.593594224247, 11615.186936914419, 11618.419079046098, 4524.434445694288, 4526.008556235246, 4556.65825063115, 4601.861944141681, 4617.0996655651825, 9190.540472976325, 9192.17270445663, 9193.126652967958, 9194.264458843276, 9203.447793132433, 5219.973900130475, 5227.869303267373, 5228.137357335997, 5229.47705229474, 5245.659032162974, 3720.6279372062577, 4388.5611438856495, 4472.217361961643, 4505.504394516666, 4777.331173635694, 3697.205100903319, 3768.8115594220726, 3843.577206507282, 3853.6917046636204, 3854.9614503855164, 1117.3631030312806, 1129.9152563557766, 1131.5191043806349, 1136.9488373023235, 1138.3056335635256, 719.5430901377558, 728.6611725547646, 876.4522173641474, 906.8231694819578, 949.9265829612493, 4359.38968544404, 4856.4415092264135, 5461.535769459561, 5669.518090962232, 5700.486956173964, 934.1779234273931, 947.2895328503622, 952.3761935931975, 974.9220062394992, 977.3354119199013, 3752.493413389212, 3895.8636447724193, 3903.375459926455, 3910.687145028391, 4016.979915100406, 8901.777455563662, 10278.383297002203, 10371.636992705218, 10481.266311358284, 10485.951404859605, 10356.326838755456, 10422.739431514121, 10457.26799124054, 10468.424236667057, 10492.527836247235, 7736.0, 7954.045514538292, 8119.106898241189, 8165.591720413956, 8231.34149268057, 5709.68596727184, 5719.428057194247, 5808.36108028125, 5879.235699359056, 5895.29256489224, 9495.28785341116, 9672.08115229052, 9863.112319891245, 9895.158911492605, 9900.504974751379, 9477.620895164117, 9930.851037234592, 9955.601775928882, 10075.143612792996, 10094.14199793026, 6772.898406523807, 6976.302369763186, 7136.500444968802, 7333.889991650023, 7345.192028876926, 8166.265036146775, 8188.7952801180445, 8246.876296855671, 8251.504909705196, 8266.090730019814, 6430.003349480838, 7191.137063552407, 7331.706731730671, 7406.481546291708, 7416.4066874648815, 7022.297770222936, 7200.351968322876, 7230.13238411394, 7236.638168091679, 7294.416446684147, 6602.273749887605, 6675.566088204251, 6691.799246022627, 6692.263850976291, 6694.531382803157, 4668.393108895958, 4727.905441891212, 4758.262469317261, 4831.758412079312, 4962.106820772296, 4190.832366705419, 4270.231358355526, 4694.2254528002495, 4809.807607695653, 5116.8720781979255], "value": 4441.048170504417, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.11639800221315, 300.9262730630996, 308.26940151840137, 319.89283589997336, 321.61406312425066, 4627.8611641650805, 4724.551167639073, 4885.511448855117, 5276.947230527693, 5287.673423693582, 4364.476262848462, 4414.62475689566, 5231.253591864273, 5252.763625636846, 5299.231611416342, 3250.203700093469, 3328.984659678797, 3729.832157552903, 3996.0609256824623, 4067.9389809152885, 3076.077176846948, 3375.0087448405434, 3791.924161516768, 3967.107400834818, 3981.9203615927663, 3629.5825980012373, 3747.7376583639157, 3847.6344747249104, 4025.5773143819915, 4036.0, 3390.542276792626, 3760.229153023637, 3931.783751893659, 3994.560598334181, 4018.457508236409, 3482.686558209748, 3498.915336245772, 3503.562054743157, 3503.9824800876086, 3507.456344266617, 5012.248162775576, 5018.673786203885, 5027.371578552681, 5033.194688849767, 5063.443021267658, 3848.84534639607, 4380.300550513311, 4542.545745425464, 4600.6549508786975, 4608.769561521944, 5477.780888764445, 5503.605931419846, 5508.7521061552325, 5525.392206857243, 5533.363663178746, 3401.9829900850586, 3848.903777405559, 3871.4192871069286, 3880.7477513961503, 3897.707671924617, 4963.081829861487, 5530.004599172187, 5845.327787304429, 5856.941430585683, 5887.205227294303, 3700.629937006278, 3898.688104951597, 3939.6060393960947, 3939.881803545898, 3946.309395855714, 3375.3249350130072, 3679.7369617685467, 3957.881263562098, 4002.9199416011515, 4004.279229738617, 3540.070661489443, 4343.609075183249, 4538.709435659187, 4589.449266088091, 4604.677672562955, 5199.558037566773, 5600.85997850057, 5615.971920140373, 5636.210930469738, 5665.951798917131, 1156.6009726644295, 1161.4831400027076, 1187.655579881842, 1284.9678758030936, 1352.3441131051432, 699.989500157498, 800.6717245929141, 807.7213361390311, 819.9139090395422, 832.6835802395051, 4435.778211089432, 4960.652754307236, 4973.875653108701, 4991.700497970133, 5166.948330516722, 5608.747606357723, 5677.290338707661, 5677.858053548612, 5733.655980641175, 5734.426557344315, 4213.894652633709, 4721.811127554928, 4896.020795840846, 5229.790808367698, 5231.607629427808, 2360.5836498101344, 2973.3310005249077, 2991.7905746597967, 3000.8949686760857, 3062.5865508156558, 4837.830675926326, 5508.76052888105, 5830.53355731549, 5942.494887934574, 5996.400359963969, 4823.855284341475, 5575.358833734092, 5807.593468457168, 5820.592558520948, 5844.6785426801935, 5636.0, 5894.528437725057, 5912.881742365129, 5914.704264786744, 5959.493443057302, 5294.708791016377, 5636.351819540805, 5682.261306030189, 5683.857903552444, 5688.317401911797, 5559.221708960829, 5604.8879022418505, 5615.803446879339, 5632.380438151718, 5678.233438485751, 5581.609287349846, 5897.203877476486, 5897.351291357862, 5898.705064746831, 5924.289085309791, 3470.097774578577, 3541.079319376929, 3768.9623103768613, 3778.678812300985, 3778.962210377861, 4710.92933606003, 5234.659747116425, 5469.972650136724, 5476.698781567052, 5742.28221472316, 4703.119926060945, 4804.543568361, 5097.6941383517105, 5244.580433565229, 5559.388467268678, 4673.929891051569, 4989.700617962933, 5344.984452953926, 5475.72621368922, 5531.285301556531, 5008.248762685626, 5072.916302376622, 5377.220303056113, 5487.670739755627, 5502.9174562382395, 4418.756968366771, 4435.445569303837, 5163.66436181647, 5460.399356070909, 5575.358833734011, 4571.51999040096, 4769.332293478847, 5091.159958606789, 5410.540104091197, 5520.751566179451], "value": 3987.506180044198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.2712511175598, 691.4779341597093, 706.1349846438114, 737.3584981066466, 752.5559919647407, 13442.39509222085, 14845.851541484595, 15265.542033738979, 15273.92363038186, 15291.388344466222, 13964.860351396508, 14030.43878244869, 14035.228062456561, 14071.155730656168, 15194.772078418833, 897.2014906733012, 919.8942121656029, 922.2667978956746, 923.8891333040044, 924.16824857628, 902.5487256371814, 915.3135148638521, 917.8118485710447, 918.9632414703403, 919.9816003679922, 968.8110818390417, 972.9367591106555, 973.226285103341, 973.6154219083458, 974.6637410093506, 963.3449254506933, 964.3201542912265, 965.4593427680521, 965.5220665770419, 967.8451447768393, 717.4726575966661, 717.5228473065405, 718.3427164144802, 718.3534818663193, 719.3382088478612, 15063.774043389358, 15076.623084422867, 15083.321250543751, 15117.31972061249, 15137.394504219817, 11918.940405297919, 11988.460519276654, 12119.818202726967, 12262.490678598062, 12496.50013999448, 10608.734781630445, 10903.34579925207, 10970.067544258747, 10975.451227438676, 11373.033292170176, 3900.7074469414906, 3970.7617542947514, 3988.78061706606, 3992.5807790182193, 4269.615734583902, 72968.0, 73723.89414158792, 76956.61521692411, 80472.59763701202, 81370.0, 7891.723789667449, 9243.953780231055, 9273.258139348836, 9288.582013809395, 9462.479563623929, 20465.2837150699, 20607.896960515103, 20658.690119648218, 20756.481087972923, 20981.0, 7062.3996960258955, 7154.821129471805, 7249.927500724925, 7298.051253337032, 7480.438967077491, 3865.43951127085, 3962.366021436614, 3970.1861118470642, 4045.8988525287104, 4269.5090064642345, 3400.472926696366, 3534.6642069003406, 3538.1862171700654, 3540.309639620301, 3546.716262698978, 617.5059952038351, 643.8454770854961, 650.1288273713152, 660.0824853453703, 663.5288944849211, 483.3547214468653, 519.9714015729096, 532.8055259830134, 540.0899484368852, 578.1674388880083, 7667.348275396542, 7708.344790692742, 7712.074551053797, 7734.1105772836845, 7785.221477852169, 556.3129535024242, 584.4652143288928, 591.2432086928738, 598.775459202799, 625.8779537990048, 3492.2666240089866, 3649.251903359805, 3653.50677658518, 3798.240351929625, 3889.0471834400187, 91792.0, 94075.059249408, 96484.0, 97099.0, 97112.0, 67525.63660271867, 75916.24083759091, 84338.73491897626, 84730.0, 84997.15002850017, 75276.0, 75416.62291688507, 75635.24364756282, 76209.85685214726, 77389.61305193439, 7111.608861512666, 7172.748953786592, 7205.927940720526, 7208.675609597685, 7244.637768111468, 33598.304186538975, 45663.77168114271, 46221.30668040049, 46753.53246467628, 46968.530314696414, 63822.68088659527, 63975.68012159909, 64004.67997659982, 64153.0, 65672.0, 10047.0, 10126.59493620247, 10358.533865975898, 10371.792564148524, 10397.32417392867, 10783.514741836792, 10930.89805160854, 10969.451527423438, 10998.835017474585, 12096.455659495168, 9602.66390676323, 9731.172850307803, 10058.698239052841, 10561.049505544537, 10569.101626361846, 10610.628627997983, 10683.94658026705, 10864.782704345711, 10872.61945831892, 11010.50452729613, 9677.70966870995, 9750.561224744763, 9833.60665573369, 9894.158996485092, 9983.900160998297, 7631.38948884099, 7887.802804929693, 8668.003179634423, 8808.779780505538, 8947.552622368725, 8425.620847061775, 8536.530490823065, 8538.743837684879, 8604.61279242423, 8786.968495868916], "value": 6722.191582103877, "unit": "score"}], "name": "dromaeo_css", "value": 7007.428268789088, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 110.0, 112.0, 112.0, 112.0, 113.0, 108.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 115.0, 167.0, 117.0, 116.0, 138.0, 116.0, 154.0, 136.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 261.0, 200.0, 183.0, 183.0, 179.0, 183.0, 183.0, 183.0, 184.0], "value": 194.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 135.0, 56.0, 57.0, 83.0, 88.0, 81.0, 55.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 102.0, 71.0, 71.0, 69.0, 97.0, 71.0, 68.0, 99.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 87.0, 83.0, 86.0, 83.0, 85.0, 82.0], "value": 84.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 97.0, 92.0, 93.0, 92.0, 93.0, 92.0, 93.0, 93.0], "value": 99.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 85.0, 84.0, 84.0, 87.0, 86.0, 84.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 57.0, 57.0, 58.0, 57.0, 57.0, 73.0, 57.0, 55.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 73.0, 77.0, 74.0, 71.0, 70.0, 70.0, 71.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 87.0, 86.0, 86.0, 85.0, 86.0, 87.0, 88.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 149.0, 151.0, 140.0, 135.0, 141.0, 142.0, 182.0, 141.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 227.0, 184.0, 163.0, 193.0, 222.0, 176.0, 173.0, 166.0, 173.0], "value": 188.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 67.0, 65.0, 66.0, 66.0, 66.0, 68.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1480.3000000000002, "alertThreshold": 2.0}]} 06:04:57 INFO - Return code: 0 06:04:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:04:57 ERROR - # TBPL SUCCESS # 06:04:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:04:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:04:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:04:57 INFO - 2016-05-26 06:04:57.350 screenresolution[1950:11327] starting screenresolution argv=screenresolution get 06:04:57 INFO - 2016-05-26 06:04:57.352 screenresolution[1950:11327] Display 0: 1600x1200x32@60 06:04:57 INFO - 2016-05-26 06:04:57.358 screenresolution[1951:11330] starting screenresolution argv=screenresolution list 06:04:57 INFO - Available Modes on Display 0 06:04:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:04:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:04:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:04:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:04:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:04:57 INFO - Intel Iris: 06:04:57 INFO - Chipset Model: Intel Iris 06:04:57 INFO - Type: GPU 06:04:57 INFO - Bus: Built-In 06:04:57 INFO - VRAM (Dynamic, Max): 1536 MB 06:04:57 INFO - Vendor: Intel (0x8086) 06:04:57 INFO - Device ID: 0x0a2e 06:04:57 INFO - Revision ID: 0x0009 06:04:57 INFO - Displays: 06:04:57 INFO - Display: 06:04:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:04:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:04:57 INFO - Display Serial Number: 1600x1200 60 06:04:57 INFO - Main Display: Yes 06:04:57 INFO - Mirror: Off 06:04:57 INFO - Online: Yes 06:04:57 INFO - Rotation: Supported 06:04:57 INFO - Return code: 0 06:04:57 INFO - Running post-action listener: _resource_record_post_action 06:04:57 INFO - ##### 06:04:57 INFO - ##### Finished run-tests step (success) 06:04:57 INFO - ##### 06:04:57 INFO - Running post-run listener: _resource_record_post_run 06:04:57 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31538688; Write bytes: 699390464; Read time: 194; Write time: 2028 06:04:57 INFO - TinderboxPrint: CPU usage
53.6% 06:04:57 INFO - TinderboxPrint: I/O read bytes / time
31,538,688 / 194 06:04:57 INFO - TinderboxPrint: I/O write bytes / time
699,390,464 / 2,028 06:04:57 INFO - TinderboxPrint: CPU idle
2,247.1 (46.4%) 06:04:57 INFO - TinderboxPrint: CPU system
963.3 (19.9%) 06:04:57 INFO - TinderboxPrint: CPU user
1,635.2 (33.7%) 06:04:57 INFO - TinderboxPrint: Swap in / out
425,414,656 / 0 06:04:57 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 374432256; Write bytes: 370632192; Read time: 28014; Write time: 814 06:04:57 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30617088; Write bytes: 298562560; Read time: 183; Write time: 1144 06:04:58 INFO - Running post-run listener: _upload_blobber_files 06:04:58 INFO - Blob upload gear active. 06:04:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:04:58 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 06:04:58 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'] 06:04:58 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 06:04:58 INFO - (blobuploader) - INFO - Open directory for files ... 06:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:59 INFO - (blobuploader) - INFO - Done attempting. 06:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:00 INFO - (blobuploader) - INFO - Done attempting. 06:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:05:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:02 INFO - (blobuploader) - INFO - Done attempting. 06:05:02 INFO - (blobuploader) - INFO - Iteration through files over. 06:05:02 INFO - Return code: 0 06:05:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:05:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:05:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e44211090a432a96012cda3548206023f16e27b4b949bab4204bdf8553556d85cafba1d05cf3d7e393dcf81c7cea6662a2e71ff6bb0a13b772faa78cafe9d81c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7eb95cc190c75219c387a379686c46a5fed1dea3cc096da85b70c217ef4f11042eef424214c28dd56223aff3f81a425d896921c18b0d6c24228104c8e4f6c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/30f83612b73d4613ddd22e9dd32f846de513a39d37a99847f372198ad908382b3d9138708db225e022a9ce837dff4aae3005689c707f433c6ed91d28fa05439d"} 06:05:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:05:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:05:02 INFO - Contents: 06:05:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e44211090a432a96012cda3548206023f16e27b4b949bab4204bdf8553556d85cafba1d05cf3d7e393dcf81c7cea6662a2e71ff6bb0a13b772faa78cafe9d81c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7eb95cc190c75219c387a379686c46a5fed1dea3cc096da85b70c217ef4f11042eef424214c28dd56223aff3f81a425d896921c18b0d6c24228104c8e4f6c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/30f83612b73d4613ddd22e9dd32f846de513a39d37a99847f372198ad908382b3d9138708db225e022a9ce837dff4aae3005689c707f433c6ed91d28fa05439d"} 06:05:02 INFO - Running post-run listener: copy_logs_to_upload_dir 06:05:02 INFO - Copying logs to upload dir... 06:05:02 INFO - mkdir: /builds/slave/test/build/upload/logs 06:05:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1300.185648 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 40 secs) (at 2016-05-26 06:05:02.718189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 06:05:02.721941) ========= 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.17y6YEG56F/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.6EcyhVUY0Z/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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/e44211090a432a96012cda3548206023f16e27b4b949bab4204bdf8553556d85cafba1d05cf3d7e393dcf81c7cea6662a2e71ff6bb0a13b772faa78cafe9d81c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7eb95cc190c75219c387a379686c46a5fed1dea3cc096da85b70c217ef4f11042eef424214c28dd56223aff3f81a425d896921c18b0d6c24228104c8e4f6c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/30f83612b73d4613ddd22e9dd32f846de513a39d37a99847f372198ad908382b3d9138708db225e022a9ce837dff4aae3005689c707f433c6ed91d28fa05439d"} build_url:https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011492 build_url: 'https://queue.taskcluster.net/v1/task/c7jxW3F_TJWSAuegYG49gQ/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/e44211090a432a96012cda3548206023f16e27b4b949bab4204bdf8553556d85cafba1d05cf3d7e393dcf81c7cea6662a2e71ff6bb0a13b772faa78cafe9d81c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7eb95cc190c75219c387a379686c46a5fed1dea3cc096da85b70c217ef4f11042eef424214c28dd56223aff3f81a425d896921c18b0d6c24228104c8e4f6c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/30f83612b73d4613ddd22e9dd32f846de513a39d37a99847f372198ad908382b3d9138708db225e022a9ce837dff4aae3005689c707f433c6ed91d28fa05439d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 06:05:02.752222) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:05:02.752554) ========= 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.17y6YEG56F/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.6EcyhVUY0Z/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.005272 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:05:02.801494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 06:05:02.801837) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 06:05:02.802168) ========= ========= Total master_lag: 0.21 =========