builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0170 starttime: 1464079288.82 results: success (0) buildid: 20160523171639 builduid: 2df6f63a3d754fefa021eb2c65da7322 revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.823031) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.823520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.823839) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.843847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.844161) ========= 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.kFpTy9GIFh/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.1UhgazwNaB/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-24 01:41:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 40.3M=0s 2016-05-24 01:41:28 (40.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091288 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.954157) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:28.954643) ========= 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.kFpTy9GIFh/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.1UhgazwNaB/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.019828 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.003718) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.004162) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kFpTy9GIFh/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.1UhgazwNaB/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-24 01:41:29,081 truncating revision to first 12 chars 2016-05-24 01:41:29,081 Setting DEBUG logging. 2016-05-24 01:41:29,082 attempt 1/10 2016-05-24 01:41:29,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/46fe2115d46a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 01:41:29,580 unpacking tar archive at: mozilla-central-46fe2115d46a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.759430 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.782802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.783203) ========= script_repo_revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.785731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.786082) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:29.807426) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-24 01:41:29.807866) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kFpTy9GIFh/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.1UhgazwNaB/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:41:29 INFO - MultiFileLogger online at 20160524 01:41:29 in /builds/slave/test 01:41:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:41:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:41:29 INFO - {'append_to_log': False, 01:41:29 INFO - 'base_work_dir': '/builds/slave/test', 01:41:29 INFO - 'blob_upload_branch': 'Firefox', 01:41:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:41:29 INFO - 'branch': 'Firefox', 01:41:29 INFO - 'buildbot_json_path': 'buildprops.json', 01:41:29 INFO - 'config_files': ('talos/mac_config.py',), 01:41:29 INFO - 'default_actions': ('clobber', 01:41:29 INFO - 'read-buildbot-config', 01:41:29 INFO - 'download-and-extract', 01:41:29 INFO - 'populate-webroot', 01:41:29 INFO - 'create-virtualenv', 01:41:29 INFO - 'install', 01:41:29 INFO - 'run-tests'), 01:41:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:41:29 INFO - 'download_minidump_stackwalk': True, 01:41:29 INFO - 'download_symbols': 'ondemand', 01:41:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:41:29 INFO - 'tooltool.py': '/tools/tooltool.py', 01:41:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:41:29 INFO - '/tools/misc-python/virtualenv.py')}, 01:41:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:41:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:41:29 INFO - 'installer_path': 'installer.exe', 01:41:29 INFO - 'log_level': 'info', 01:41:29 INFO - 'log_name': 'talos', 01:41:29 INFO - 'log_to_console': True, 01:41:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:41:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:41:29 INFO - 'opt_config_files': (), 01:41:29 INFO - 'pip_index': False, 01:41:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:29 INFO - 'cmd': ('bash', 01:41:29 INFO - '-c', 01:41:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:29 INFO - 'enabled': True, 01:41:29 INFO - 'halt_on_failure': False, 01:41:29 INFO - 'name': 'check_screen_resolution'},), 01:41:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:29 INFO - 'cmd': ('bash', 01:41:29 INFO - '-c', 01:41:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:29 INFO - 'enabled': True, 01:41:29 INFO - 'halt_on_failure': False, 01:41:29 INFO - 'name': 'check_screen_resolution'},), 01:41:29 INFO - 'run_cmd_checks_enabled': True, 01:41:29 INFO - 'sps_profile': False, 01:41:29 INFO - 'sps_profile_interval': 0, 01:41:29 INFO - 'suite': 'dromaeojs-e10s', 01:41:29 INFO - 'system_bits': '32', 01:41:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:41:29 INFO - 'title': 't-yosemite-r7-0170', 01:41:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:41:29 INFO - 'use_talos_json': True, 01:41:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:41:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:41:29 INFO - 'work_dir': 'build'} 01:41:29 INFO - ##### 01:41:29 INFO - ##### Running clobber step. 01:41:29 INFO - ##### 01:41:29 INFO - Running pre-action listener: _resource_record_pre_action 01:41:29 INFO - Running main action method: clobber 01:41:29 INFO - rmtree: /builds/slave/test/build 01:41:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:41:31 INFO - Running post-action listener: _resource_record_post_action 01:41:31 INFO - ##### 01:41:31 INFO - ##### Finished clobber step (success) 01:41:31 INFO - ##### 01:41:31 INFO - ##### 01:41:31 INFO - ##### Running read-buildbot-config step. 01:41:31 INFO - ##### 01:41:31 INFO - Running pre-action listener: _resource_record_pre_action 01:41:31 INFO - Running main action method: read_buildbot_config 01:41:31 INFO - Using buildbot properties: 01:41:31 INFO - { 01:41:31 INFO - "project": "", 01:41:31 INFO - "product": "firefox", 01:41:31 INFO - "script_repo_revision": "production", 01:41:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 01:41:31 INFO - "repository": "", 01:41:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 01:41:31 INFO - "buildid": "20160523171639", 01:41:31 INFO - "pgo_build": "False", 01:41:31 INFO - "basedir": "/builds/slave/test", 01:41:31 INFO - "buildnumber": 283, 01:41:31 INFO - "slavename": "t-yosemite-r7-0170", 01:41:31 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:41:31 INFO - "platform": "yosemite_r7", 01:41:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:41:31 INFO - "branch": "mozilla-central", 01:41:31 INFO - "repo_path": "mozilla-central", 01:41:31 INFO - "revision": "46fe2115d46a5bb40523b8466341d8f9a26e1bdf", 01:41:31 INFO - "stage_platform": "macosx64", 01:41:31 INFO - "builduid": "2df6f63a3d754fefa021eb2c65da7322", 01:41:31 INFO - "slavebuilddir": "test" 01:41:31 INFO - } 01:41:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:41:31 INFO - Running post-action listener: _resource_record_post_action 01:41:31 INFO - ##### 01:41:31 INFO - ##### Finished read-buildbot-config step (success) 01:41:31 INFO - ##### 01:41:31 INFO - ##### 01:41:31 INFO - ##### Running download-and-extract step. 01:41:31 INFO - ##### 01:41:31 INFO - Running pre-action listener: _resource_record_pre_action 01:41:31 INFO - Running main action method: download_and_extract 01:41:31 INFO - mkdir: /builds/slave/test/build/tests 01:41: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')]} 01:41:31 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:41:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:33 INFO - Downloaded 1199 bytes. 01:41:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:33 INFO - Using the following test package requirements: 01:41:33 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:33 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:41:33 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:41:33 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'jsshell-mac.zip'], 01:41:33 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:41:33 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:33 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:41:33 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:41:33 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:41:33 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:33 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:41:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:41:33 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')]} 01:41:33 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:35 INFO - Downloaded 18630520 bytes. 01:41:35 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'] 01:41:35 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 01:41:38 INFO - Return code: 0 01:41:38 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 01:41:38 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')]} 01:41:38 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:41 INFO - Downloaded 18630520 bytes. 01:41:41 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'] 01:41:41 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 01:41:44 INFO - Return code: 0 01:41:44 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')]} 01:41:44 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:41:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:46 INFO - Downloaded 10840359 bytes. 01:41:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:41:46 INFO - Return code: 0 01:41: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')]} 01:41:46 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:41:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:41:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:51 INFO - Downloaded 110231948 bytes. 01:41:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:51 INFO - mkdir: /builds/slave/test/properties 01:41:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:51 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:51 INFO - Contents: 01:41:51 INFO - build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:55 INFO - Running post-action listener: _resource_record_post_action 01:41:55 INFO - Running post-action listener: set_extra_try_arguments 01:41:55 INFO - ##### 01:41:55 INFO - ##### Finished download-and-extract step (success) 01:41:55 INFO - ##### 01:41:55 INFO - ##### 01:41:55 INFO - ##### Running populate-webroot step. 01:41:55 INFO - ##### 01:41:55 INFO - Running pre-action listener: _resource_record_pre_action 01:41:55 INFO - Running main action method: populate_webroot 01:41:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:55 INFO - '--tppagecycles', 01:41:55 INFO - '7'], 01:41:55 INFO - 'tests': ['tsvgm']}}, 01:41:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['damp', 'tps']}, 01:41:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'tests': ['damp', 'tps']}, 01:41:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['dromaeo_dom']}, 01:41:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['a11yr', 01:41:55 INFO - 'ts_paint', 01:41:55 INFO - 'tpaint', 01:41:55 INFO - 'sessionrestore', 01:41:55 INFO - 'sessionrestore_no_auto_restore', 01:41:55 INFO - 'tabpaint']}, 01:41:55 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:55 INFO - 'ts_paint', 01:41:55 INFO - 'tpaint', 01:41:55 INFO - 'sessionrestore', 01:41:55 INFO - 'sessionrestore_no_auto_restore', 01:41:55 INFO - 'tabpaint']}, 01:41:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:55 INFO - 'ts_paint', 01:41:55 INFO - 'tpaint', 01:41:55 INFO - 'sessionrestore', 01:41:55 INFO - 'sessionrestore_no_auto_restore', 01:41:55 INFO - 'tabpaint']}, 01:41:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:55 INFO - 'ts_paint', 01:41:55 INFO - 'tpaint', 01:41:55 INFO - 'sessionrestore', 01:41:55 INFO - 'sessionrestore_no_auto_restore', 01:41:55 INFO - 'tabpaint']}, 01:41:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['a11yr', 01:41:55 INFO - 'ts_paint', 01:41:55 INFO - 'tpaint', 01:41:55 INFO - 'sessionrestore', 01:41:55 INFO - 'sessionrestore_no_auto_restore', 01:41:55 INFO - 'tabpaint']}, 01:41:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['a11yr', 01:41:55 INFO - 'ts_paint', 01:41:55 INFO - 'tpaint', 01:41:55 INFO - 'sessionrestore', 01:41:55 INFO - 'sessionrestore_no_auto_restore', 01:41:55 INFO - 'tabpaint']}, 01:41:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['tsvgx', 01:41:55 INFO - 'tsvgr_opacity', 01:41:55 INFO - 'tart', 01:41:55 INFO - 'tscrollx', 01:41:55 INFO - 'cart']}, 01:41:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:55 INFO - 'tsvgr_opacity', 01:41:55 INFO - 'tart', 01:41:55 INFO - 'tscrollx', 01:41:55 INFO - 'cart']}, 01:41:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'talos_options': ['--disable-e10s'], 01:41:55 INFO - 'tests': ['tp5o']}, 01:41:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'tests': ['tp5o']}, 01:41:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'talos_options': ['--disable-e10s', 01:41:55 INFO - '--xperf_path', 01:41:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:55 INFO - 'tests': ['tp5n']}, 01:41:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:55 INFO - 'talos_options': ['--xperf_path', 01:41:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:55 INFO - 'tests': ['tp5n']}}, 01:41:55 INFO - 'talos.zip': {'path': '', 01:41:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:55 INFO - Running post-action listener: _resource_record_post_action 01:41:55 INFO - ##### 01:41:55 INFO - ##### Finished populate-webroot step (success) 01:41:55 INFO - ##### 01:41:55 INFO - ##### 01:41:55 INFO - ##### Running create-virtualenv step. 01:41:55 INFO - ##### 01:41:55 INFO - Running pre-action listener: _resource_record_pre_action 01:41:55 INFO - Running main action method: create_virtualenv 01:41:55 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41:55 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 01:41:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:55 INFO - Using real prefix '/tools/python27' 01:41:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:56 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:59 INFO - Installing pip.................done. 01:41:59 INFO - Return code: 0 01:41:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:59 INFO - 'HOME': '/Users/cltbld', 01:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:59 INFO - 'LOGNAME': 'cltbld', 01:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:59 INFO - 'MOZ_NO_REMOTE': '1', 01:41:59 INFO - 'NO_EM_RESTART': '1', 01:41:59 INFO - 'PAGER': '/bin/cat', 01:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:59 INFO - 'PWD': '/builds/slave/test', 01:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:59 INFO - 'SHELL': '/bin/bash', 01:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:41:59 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:59 INFO - 'USER': 'cltbld', 01:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:59 INFO - 'XPC_FLAGS': '0x0', 01:41:59 INFO - 'XPC_SERVICE_NAME': '0', 01:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:59 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 01:41:59 INFO - Cleaning up... 01:41:59 INFO - Return code: 0 01:41:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:59 INFO - 'HOME': '/Users/cltbld', 01:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:59 INFO - 'LOGNAME': 'cltbld', 01:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:59 INFO - 'MOZ_NO_REMOTE': '1', 01:41:59 INFO - 'NO_EM_RESTART': '1', 01:41:59 INFO - 'PAGER': '/bin/cat', 01:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:59 INFO - 'PWD': '/builds/slave/test', 01:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:59 INFO - 'SHELL': '/bin/bash', 01:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:41:59 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:59 INFO - 'USER': 'cltbld', 01:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:59 INFO - 'XPC_FLAGS': '0x0', 01:41:59 INFO - 'XPC_SERVICE_NAME': '0', 01:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:59 INFO - Downloading/unpacking psutil>=3.1.1 01:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:42:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:42:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:42:02 INFO - Installing collected packages: psutil 01:42:02 INFO - Running setup.py install for psutil 01:42:02 INFO - building 'psutil._psutil_osx' extension 01:42:02 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 01:42:02 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 01:42:02 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 01:42:03 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 01:42:03 INFO - building 'psutil._psutil_posix' extension 01:42:03 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 01:42:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:42:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:42:03 INFO - ^ 01:42:03 INFO - 1 warning generated. 01:42:03 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 01:42:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:42:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:42:03 INFO - Successfully installed psutil 01:42:03 INFO - Cleaning up... 01:42:03 INFO - Return code: 0 01:42:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:42:03 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')]} 01:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:03 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')]} 01:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:03 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:03 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 01:42:03 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 01:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:03 INFO - 'HOME': '/Users/cltbld', 01:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:03 INFO - 'LOGNAME': 'cltbld', 01:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:03 INFO - 'MOZ_NO_REMOTE': '1', 01:42:03 INFO - 'NO_EM_RESTART': '1', 01:42:03 INFO - 'PAGER': '/bin/cat', 01:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:03 INFO - 'PWD': '/builds/slave/test', 01:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:03 INFO - 'SHELL': '/bin/bash', 01:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:03 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:03 INFO - 'USER': 'cltbld', 01:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:03 INFO - 'XPC_FLAGS': '0x0', 01:42:03 INFO - 'XPC_SERVICE_NAME': '0', 01:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 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 01:42:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:42:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:42:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:42:06 INFO - Installing collected packages: mozsystemmonitor 01:42:06 INFO - Running setup.py install for mozsystemmonitor 01:42:06 INFO - Successfully installed mozsystemmonitor 01:42:06 INFO - Cleaning up... 01:42:06 INFO - Return code: 0 01:42:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:42: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')]} 01:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42: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')]} 01:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:06 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:06 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 01:42:06 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 01:42:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:06 INFO - 'HOME': '/Users/cltbld', 01:42:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:06 INFO - 'LOGNAME': 'cltbld', 01:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:06 INFO - 'MOZ_NO_REMOTE': '1', 01:42:06 INFO - 'NO_EM_RESTART': '1', 01:42:06 INFO - 'PAGER': '/bin/cat', 01:42:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:06 INFO - 'PWD': '/builds/slave/test', 01:42:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:06 INFO - 'SHELL': '/bin/bash', 01:42:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:06 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:06 INFO - 'USER': 'cltbld', 01:42:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:06 INFO - 'XPC_FLAGS': '0x0', 01:42:06 INFO - 'XPC_SERVICE_NAME': '0', 01:42:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:06 INFO - Downloading/unpacking blobuploader==1.2.4 01:42:06 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 01:42:06 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 01:42:06 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 01:42:06 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 01:42:06 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 01:42:06 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 01:42:08 INFO - Downloading blobuploader-1.2.4.tar.gz 01:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:42:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:42:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 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 01:42:09 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 01:42:10 INFO - Downloading docopt-0.6.1.tar.gz 01:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:42:10 INFO - Installing collected packages: blobuploader, requests, docopt 01:42:10 INFO - Running setup.py install for blobuploader 01:42:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:42:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:42:10 INFO - Running setup.py install for requests 01:42:10 INFO - Running setup.py install for docopt 01:42:11 INFO - Successfully installed blobuploader requests docopt 01:42:11 INFO - Cleaning up... 01:42:11 INFO - Return code: 0 01:42:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:11 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:11 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 01:42:11 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 01:42:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:11 INFO - 'HOME': '/Users/cltbld', 01:42:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:11 INFO - 'LOGNAME': 'cltbld', 01:42:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:11 INFO - 'MOZ_NO_REMOTE': '1', 01:42:11 INFO - 'NO_EM_RESTART': '1', 01:42:11 INFO - 'PAGER': '/bin/cat', 01:42:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:11 INFO - 'PWD': '/builds/slave/test', 01:42:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:11 INFO - 'SHELL': '/bin/bash', 01:42:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:11 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:11 INFO - 'USER': 'cltbld', 01:42:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:11 INFO - 'XPC_FLAGS': '0x0', 01:42:11 INFO - 'XPC_SERVICE_NAME': '0', 01:42:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-JyCRsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-jDXIp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QqRf4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ZrjSQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-uy7Kwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-g8E_42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QdYtOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5fHgwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-f2k5Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XrfbSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-p67RKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zkY_OD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OAiAH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-os9y4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-6Lru5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:12 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YOBaVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_zBSZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:42:13 INFO - Running setup.py install for manifestparser 01:42:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:42:13 INFO - Running setup.py install for mozcrash 01:42:13 INFO - Running setup.py install for mozdebug 01:42:13 INFO - Running setup.py install for mozdevice 01:42:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:42:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:42:13 INFO - Running setup.py install for mozfile 01:42:13 INFO - Running setup.py install for mozhttpd 01:42:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Running setup.py install for mozinfo 01:42:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Running setup.py install for mozInstall 01:42:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Running setup.py install for mozleak 01:42:14 INFO - Running setup.py install for mozlog 01:42:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Running setup.py install for moznetwork 01:42:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:42:14 INFO - Running setup.py install for mozprocess 01:42:14 INFO - Running setup.py install for mozprofile 01:42:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:42:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:42:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:42:15 INFO - Running setup.py install for mozrunner 01:42:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:42:15 INFO - Running setup.py install for mozscreenshot 01:42:15 INFO - Running setup.py install for moztest 01:42:15 INFO - Running setup.py install for mozversion 01:42:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:42:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:42:15 INFO - Cleaning up... 01:42:15 INFO - Return code: 0 01:42:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:15 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')]} 01:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:15 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')]} 01:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:15 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:15 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 01:42:15 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 01:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:15 INFO - 'HOME': '/Users/cltbld', 01:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:15 INFO - 'LOGNAME': 'cltbld', 01:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:15 INFO - 'MOZ_NO_REMOTE': '1', 01:42:15 INFO - 'NO_EM_RESTART': '1', 01:42:15 INFO - 'PAGER': '/bin/cat', 01:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:15 INFO - 'PWD': '/builds/slave/test', 01:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:15 INFO - 'SHELL': '/bin/bash', 01:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:15 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:15 INFO - 'USER': 'cltbld', 01:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:15 INFO - 'XPC_FLAGS': '0x0', 01:42:15 INFO - 'XPC_SERVICE_NAME': '0', 01:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-RHLrEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Lus4eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-jyk3Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-vPBWd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zK52li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-k1kbZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-c_PAy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iFPZUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Oy1k7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:16 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)) 01:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Od7CqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-3NLEIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-F_ESfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-DhWEVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-dVFXm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-m6IMrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-KzImM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:17 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)) 01:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-AkEeFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:17 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)) 01:42:17 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)) 01:42:17 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)) 01:42:17 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)) 01:42:17 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)) 01:42:17 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)) 01:42:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:20 INFO - Downloading blessings-1.6.tar.gz 01:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:42:20 INFO - Installing collected packages: blessings 01:42:20 INFO - Running setup.py install for blessings 01:42:20 INFO - Successfully installed blessings 01:42:20 INFO - Cleaning up... 01:42:20 INFO - Return code: 0 01:42:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:42:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:20 INFO - Reading from file tmpfile_stdout 01:42:20 INFO - Current package versions: 01:42:20 INFO - blessings == 1.6 01:42:20 INFO - blobuploader == 1.2.4 01:42:20 INFO - docopt == 0.6.1 01:42:20 INFO - manifestparser == 1.1 01:42:20 INFO - mozInstall == 1.12 01:42:20 INFO - mozcrash == 0.17 01:42:20 INFO - mozdebug == 0.1 01:42:20 INFO - mozdevice == 0.48 01:42:20 INFO - mozfile == 1.2 01:42:20 INFO - mozhttpd == 0.7 01:42:20 INFO - mozinfo == 0.9 01:42:20 INFO - mozleak == 0.1 01:42:20 INFO - mozlog == 3.2 01:42:20 INFO - moznetwork == 0.27 01:42:20 INFO - mozprocess == 0.22 01:42:20 INFO - mozprofile == 0.28 01:42:20 INFO - mozrunner == 6.11 01:42:20 INFO - mozscreenshot == 0.1 01:42:20 INFO - mozsystemmonitor == 0.3 01:42:20 INFO - moztest == 0.7 01:42:20 INFO - mozversion == 1.4 01:42:20 INFO - psutil == 3.1.1 01:42:20 INFO - requests == 1.2.3 01:42:20 INFO - wsgiref == 0.1.2 01:42:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:20 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:20 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 01:42:20 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 01:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:20 INFO - 'HOME': '/Users/cltbld', 01:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:20 INFO - 'LOGNAME': 'cltbld', 01:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:20 INFO - 'MOZ_NO_REMOTE': '1', 01:42:20 INFO - 'NO_EM_RESTART': '1', 01:42:20 INFO - 'PAGER': '/bin/cat', 01:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:20 INFO - 'PWD': '/builds/slave/test', 01:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:20 INFO - 'SHELL': '/bin/bash', 01:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:20 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:20 INFO - 'USER': 'cltbld', 01:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:20 INFO - 'XPC_FLAGS': '0x0', 01:42:20 INFO - 'XPC_SERVICE_NAME': '0', 01:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 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)) 01:42:21 INFO - Cleaning up... 01:42:21 INFO - Return code: 0 01:42:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:42:21 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')]} 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:21 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')]} 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:21 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:21 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 01:42:21 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 01:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:21 INFO - 'HOME': '/Users/cltbld', 01:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:21 INFO - 'LOGNAME': 'cltbld', 01:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:21 INFO - 'MOZ_NO_REMOTE': '1', 01:42:21 INFO - 'NO_EM_RESTART': '1', 01:42:21 INFO - 'PAGER': '/bin/cat', 01:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:21 INFO - 'PWD': '/builds/slave/test', 01:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:21 INFO - 'SHELL': '/bin/bash', 01:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:21 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:21 INFO - 'USER': 'cltbld', 01:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:21 INFO - 'XPC_FLAGS': '0x0', 01:42:21 INFO - 'XPC_SERVICE_NAME': '0', 01:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:21 INFO - Downloading/unpacking jsonschema 01:42:21 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 01:42:21 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 01:42:21 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 01:42:21 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 01:42:21 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 01:42:21 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 01:42:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:42:23 INFO - Downloading/unpacking functools32 (from jsonschema) 01:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:24 INFO - Downloading functools32-3.2.3-2.tar.gz 01:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:42:24 INFO - warning: no files found matching '*.txt' 01:42:24 INFO - no previously-included directories found matching 'build' 01:42:24 INFO - no previously-included directories found matching 'dist' 01:42:24 INFO - no previously-included directories found matching '.git*' 01:42:24 INFO - Installing collected packages: jsonschema, functools32 01:42:24 INFO - Running setup.py install for functools32 01:42:24 INFO - warning: no files found matching '*.txt' 01:42:24 INFO - no previously-included directories found matching 'build' 01:42:24 INFO - no previously-included directories found matching 'dist' 01:42:24 INFO - no previously-included directories found matching '.git*' 01:42:24 INFO - Successfully installed jsonschema functools32 01:42:24 INFO - Cleaning up... 01:42:24 INFO - Return code: 0 01:42:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:42: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')]} 01:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42: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')]} 01:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:24 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 0x10a45ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a464570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e2bc8de40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, '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.kFpTy9GIFh/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.1UhgazwNaB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 01:42:24 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 01:42:24 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 01:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:24 INFO - 'HOME': '/Users/cltbld', 01:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:24 INFO - 'LOGNAME': 'cltbld', 01:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:24 INFO - 'MOZ_NO_REMOTE': '1', 01:42:24 INFO - 'NO_EM_RESTART': '1', 01:42:24 INFO - 'PAGER': '/bin/cat', 01:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:24 INFO - 'PWD': '/builds/slave/test', 01:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:24 INFO - 'SHELL': '/bin/bash', 01:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:42:24 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:24 INFO - 'USER': 'cltbld', 01:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:24 INFO - 'XPC_FLAGS': '0x0', 01:42:24 INFO - 'XPC_SERVICE_NAME': '0', 01:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:24 INFO - Downloading/unpacking flake8 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:42:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:42:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 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 01:42:27 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 01:42:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:42:28 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 01:42:28 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 01:42:28 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 01:42:28 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 01:42:28 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 01:42:28 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 01:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:28 INFO - no previously-included directories found matching 'docs/_build' 01:42:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:42:28 INFO - Running setup.py install for pep8 01:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:28 INFO - no previously-included directories found matching 'docs/_build' 01:42:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:42:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:42:29 INFO - Cleaning up... 01:42:29 INFO - Return code: 0 01:42:29 INFO - Running post-action listener: _resource_record_post_action 01:42:29 INFO - Running post-action listener: _start_resource_monitoring 01:42:29 INFO - Starting resource monitoring. 01:42:29 INFO - ##### 01:42:29 INFO - ##### Finished create-virtualenv step (success) 01:42:29 INFO - ##### 01:42:29 INFO - ##### 01:42:29 INFO - ##### Running install step. 01:42:29 INFO - ##### 01:42:29 INFO - Running pre-action listener: _resource_record_pre_action 01:42:29 INFO - Running main action method: install 01:42:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:29 INFO - Reading from file tmpfile_stdout 01:42:29 INFO - Detecting whether we're running mozinstall >=1.0... 01:42:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:42:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:42:29 INFO - Reading from file tmpfile_stdout 01:42:29 INFO - Output received: 01:42:29 INFO - Usage: mozinstall [options] installer 01:42:29 INFO - Options: 01:42:29 INFO - -h, --help show this help message and exit 01:42:29 INFO - -d DEST, --destination=DEST 01:42:29 INFO - Directory to install application into. [default: 01:42:29 INFO - "/builds/slave/test"] 01:42:29 INFO - --app=APP Application being installed. [default: firefox] 01:42:29 INFO - mkdir: /builds/slave/test/build/application 01:42:29 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'] 01:42:29 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 01:43:03 INFO - Reading from file tmpfile_stdout 01:43:03 INFO - Output received: 01:43:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:43:03 INFO - Running post-action listener: _resource_record_post_action 01:43:03 INFO - ##### 01:43:03 INFO - ##### Finished install step (success) 01:43:03 INFO - ##### 01:43:03 INFO - ##### 01:43:03 INFO - ##### Running run-tests step. 01:43:03 INFO - ##### 01:43:03 INFO - Running pre-action listener: _resource_record_pre_action 01:43:03 INFO - Running main action method: run_tests 01:43:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:43:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:43:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:43:03 INFO - 2016-05-24 01:43:03.489 screenresolution[2230:21369] starting screenresolution argv=screenresolution get 01:43:03 INFO - 2016-05-24 01:43:03.492 screenresolution[2230:21369] Display 0: 1600x1200x32@60 01:43:03 INFO - 2016-05-24 01:43:03.498 screenresolution[2231:21372] starting screenresolution argv=screenresolution list 01:43:03 INFO - Available Modes on Display 0 01:43:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:43:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:43:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:43:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:43:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:43:03 INFO - Intel Iris: 01:43:03 INFO - Chipset Model: Intel Iris 01:43:03 INFO - Type: GPU 01:43:03 INFO - Bus: Built-In 01:43:03 INFO - VRAM (Dynamic, Max): 1536 MB 01:43:03 INFO - Vendor: Intel (0x8086) 01:43:03 INFO - Device ID: 0x0a2e 01:43:03 INFO - Revision ID: 0x0009 01:43:03 INFO - Displays: 01:43:03 INFO - Display: 01:43:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:43:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:43:03 INFO - Display Serial Number: 1600x1200 60 01:43:03 INFO - Main Display: Yes 01:43:03 INFO - Mirror: Off 01:43:03 INFO - Online: Yes 01:43:03 INFO - Rotation: Supported 01:43:03 INFO - Return code: 0 01:43:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:43:03 INFO - Python 2.7.3 01:43:03 INFO - Return code: 0 01:43:03 INFO - grabbing minidump binary from tooltool 01:43:03 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')]} 01:43:03 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 0x10a302d30>, '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 0x10a126db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2f9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:43:03 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 01:43:03 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 01:43:03 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 01:43:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:43:03 INFO - Return code: 0 01:43:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:43:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:43:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:43:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:43:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:43:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:43:04 INFO - Return code: 0 01:43:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:43:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kFpTy9GIFh/Render', 01:43:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:43:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:43:04 INFO - 'HOME': '/Users/cltbld', 01:43:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:43:04 INFO - 'LOGNAME': 'cltbld', 01:43:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:43:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:43:04 INFO - 'MOZ_NO_REMOTE': '1', 01:43:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:43:04 INFO - 'NO_EM_RESTART': '1', 01:43:04 INFO - 'PAGER': '/bin/cat', 01:43:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:43:04 INFO - 'PWD': '/builds/slave/test', 01:43:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:43:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:43:04 INFO - 'SHELL': '/bin/bash', 01:43:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1UhgazwNaB/Listeners', 01:43:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 01:43:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:43:04 INFO - 'USER': 'cltbld', 01:43:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:43:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:43:04 INFO - 'XPC_FLAGS': '0x0', 01:43:04 INFO - 'XPC_SERVICE_NAME': '0', 01:43:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:43:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:43:05 INFO - mozversion application_buildid: 20160523171639 01:43:05 INFO - mozversion application_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:43:05 INFO - mozversion application_display_name: Nightly 01:43:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:43:05 INFO - mozversion application_name: Firefox 01:43:05 INFO - mozversion application_remotingname: firefox 01:43:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:43:05 INFO - mozversion application_vendor: Mozilla 01:43:05 INFO - mozversion application_version: 49.0a1 01:43:05 INFO - mozversion platform_buildid: 20160523171639 01:43:05 INFO - mozversion platform_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:43:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:43:05 INFO - mozversion platform_version: 49.0a1 01:43:05 INFO - using testdate: 1464079385 01:43:05 INFO - actual date: 1464079385 01:43:05 INFO - starting webserver on 'localhost:52582' 01:43:05 INFO - SUITE-START | Running 2 tests 01:43:05 INFO - TEST-START | dromaeo_css 01:43:05 INFO - operating with platform_type : mac_ 01:43:05 INFO - Initialising browser for dromaeo_css test... 01:43:05 INFO - TEST-INFO | started process 2249 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpke8zZn/profile http://localhost:52582/getInfo.html) 01:43:08 INFO - PROCESS | 2249 | __metrics Screen width/height:1600/1200 01:43:08 INFO - PROCESS | 2249 | colorDepth:24 01:43:08 INFO - PROCESS | 2249 | Browser inner width/height: 1024/689 01:43:08 INFO - PROCESS | 2249 | __metrics 01:43:08 ERROR - PROCESS | 2249 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 01:43:08 INFO - TEST-INFO | 2249: exit 0 01:43:08 INFO - Browser initialized. 01:43:08 INFO - Running cycle 1/1 for dromaeo_css test... 01:43:08 INFO - TEST-INFO | started process 2252 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpke8zZn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:43:20 INFO - PROCESS | 2252 | RSS: Main: 250380288 01:43:20 INFO - PROCESS | 2252 | 01:44:10 INFO - PROCESS | 2252 | 1464079450540 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] 01:46:21 INFO - PROCESS | 2252 | Cycle 1(1): loaded http://localhost:52582/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52582/tests/dromaeo/cssquery-ext.html) 01:46:22 INFO - PROCESS | 2252 | RSS: Main: 261382144 01:46:22 INFO - PROCESS | 2252 | 01:49:24 INFO - PROCESS | 2252 | Cycle 1(1): loaded http://localhost:52582/tests/dromaeo/cssquery-ext.html (next: http://localhost:52582/tests/dromaeo/cssquery-jquery.html) 01:49:25 INFO - PROCESS | 2252 | RSS: Main: 273752064 01:49:25 INFO - PROCESS | 2252 | 01:52:26 INFO - PROCESS | 2252 | Cycle 1(1): loaded http://localhost:52582/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52582/tests/dromaeo/cssquery-mootools.html) 01:52:27 INFO - PROCESS | 2252 | RSS: Main: 275992576 01:52:27 INFO - PROCESS | 2252 | 01:55:29 INFO - PROCESS | 2252 | Cycle 1(1): loaded http://localhost:52582/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52582/tests/dromaeo/cssquery-prototype.html) 01:55:30 INFO - PROCESS | 2252 | RSS: Main: 277561344 01:55:30 INFO - PROCESS | 2252 | 01:58:32 INFO - PROCESS | 2252 | Cycle 1(1): loaded http://localhost:52582/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52582/tests/dromaeo/cssquery-yui.html) 01:58:33 INFO - PROCESS | 2252 | RSS: Main: 280383488 01:58:33 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | Cycle 1(1): loaded http://localhost:52582/tests/dromaeo/cssquery-yui.html (next: http://localhost:52582/tests/dromaeo/cssquery-dojo.html) 02:01:36 INFO - PROCESS | 2252 | RSS: Main: 281395200 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | __start_tp_report 02:01:36 INFO - PROCESS | 2252 | _x_x_mozilla_page_load 02:01:36 INFO - PROCESS | 2252 | _x_x_mozilla_page_load_details 02:01:36 INFO - PROCESS | 2252 | |i|pagename|runs| 02:01:36 INFO - PROCESS | 2252 | |0;dojo.html;2791.567307067404;3529.4000019996597;3534.1341371364006;3560.964390356096;3563.180468492245;5909.054551271792;5993.670348130857;6041.1240370146415;6050.213472248607;6052.515798736102;5796.594238403313;5836.212111364965;5946.851328716773;5955.404459554043;6053.152558641794;4878.317035614999;5006.474320196382;5317.521423071923;5499.3675727291275;5509.834704958857;196.63886431918374;4929.457759646436;5328.733563321838;5434.755436005389;5467.808626698066;5410.56715462766;5434.266374039494;5441.59188060897;5452.345718513804;5462.918056229139;5457.345118585756;5477.698726570037;5482.479164479408;5501.944980550184;5539.944600553983;5274.997750427445;5334.986352592995;5358.705271210121;5404.32445944257;5434.375046869621;5672.546196304285;5770.596058275923;5824.271966004249;5853.560982926298;5859.384764599741;6236.719347629368;6249.843753906143;6313.273973493061;6316.463100636452;6334.334894836022;6230.657313847737;6272.65500397478;6287.811365659038;6356.269029061625;6363.268224154235;6095.512359011268;6098.176746139303;6098.993666045098;6107.358727333655;6117.296510901214;6250.531210159256;6340.239171299474;6488.513361497907;6505.349465053551;6519.217693876765;6235.189425374672;6235.407636274546;6292.119103325446;6292.968535157295;6295.464885484785;6297.811065668037;6304.243490781136;6377.107204991305;6381.457576106083;6401.263854656681;5215.24378965048;6231.4391704746795;6232.8130156095385;6292.528060395397;6295.622262664255;5162.380514338303;5294.311739473844;6197.907031394533;6278.811635650938;6305.3379395163765;5066.822661206914;5091.109055915274;5101.0818052750865;5102.183650615883;5162.045021670928;3909.139989202371;4450.3991961085485;4474.798634061475;4502.4371953505815;4560.931586026212;5869.354371019184;5914.970425147847;5961.552883533665;6005.969970150209;6048.0625503047095;6275.06253225873;6307.05394190875;6312.936870631327;6321.051842223703;6322.4942004639515;4942.752862356868;5820.534357251409;5858.619189752652;5929.970350148222;6005.279366475964;5479.205515200353;5512.39363669996;5530.86172845674;5536.695481748542;5545.306836645419;6538.738450461929;6549.050387693853;6549.312322206099;6549.770758023542;6561.868762624721;6228.4705800005695;6259.061140828912;6322.589031712924;6324.841878953063;6330.082138090043;6523.804285871432;6555.115059466911;6559.27847936717;6561.278259391368;6577.901331479936;5516.14499752539;6197.287311959002;6317.747290108344;6320.5259605528845;6338.429541341301;6269.310375858743;6292.685365731787;6293;6332.715027823851;6339.936600633934;6140.846478838065;6245.469135123383;6312.968435157794;6321.367863213826;6343;6121.2700689216845;6146.832101900624;6288.811335659938;6337.4613157882095;6351.396617321347;6097.847553811012;6100.429789472632;6172.9074063889975;6222.066689996536;6229.221347331583;6122.173506576733;6242.063690446469;6297.338779428277;6301.117843501822;6315.147455093504;6024.367441418588;6052.05922953916;6057.1519987200945;6172.660503672341;6177.938220617736 02:01:36 INFO - PROCESS | 2252 | |1;ext.html;500.87728506515907;521.8434469659103;527.6306585390228;535.641120449299;552.4944675621806;31289.374212515737;40245.79877100618;41169.79415102928;41186;41186.76439706806;41814.37278440826;41944;42743.3588496172;42811.57188428115;42863;9478.76303092423;9622.951885240564;9671.984441633633;9690.951545242264;9708.90291097087;9597.856032159523;9690.56392462341;9704.563294651758;9705.320627556073;9717.31978761487;9671.758206044835;9731.270154738388;9743.561539730728;9744.220462362993;9764.340062189409;9628.277879159092;9674.56464459101;9705.932347441776;9706.417614943126;9711.61153553857;9553;9560.808783824354;9657.90342096584;9677;9680.080392362714;35020;38776.61223387758;38872.222555549015;38991.8050409749;39255.41116883249;2717.8097533172686;2953.5422009588547;3047.116336262481;3236.255661197914;3269.9045819650446;26288.73711262882;27253.318667033283;28024.159275221573;28686.85252589894;28809;2190.485235969546;2618.0967566189634;2624.514464824014;2714.1586108306487;2720.986395068032;321263;396577.0342296569;405831;408069;409130;33021.66978330234;33599.83200084033;33665.49501757476;33719.32561348759;34659.30681386358;35213.82393088018;35608;35632.82183589065;35696.46455303173;35817.64182358195;15175.317110729822;15246.847531524765;15288.923555382153;15326.616834579225;15362.846371536365;12813.615591532269;12875.871241287654;12881.549145779853;12891.871081289068;12905.870941290656;7821.530708157528;7822.804429889185;7844.176361482077;7878.330341920886;7890.447668663139;3277.737780977516;3285.293661862663;3291.8518666660057;3293.2425542125447;3317.767756257039;977.5454413697664;1032.0608246495653;1039.1028185788102;1049.751908302542;1114.3870871020906;23408.414789630395;28422.715772842006;29505.704942950295;29576.852115739282;30015.399692006038;1051.7002654243504;1074.0923919288189;1084.4686103809076;1090.2095980414294;1100.0429626225234;12926.159799612995;14574.854251457562;15135.772963405558;15180.620484487976;15407;570717;582056;585413;586243;592224;58995.82008359895;59417;59463;59627.105593416134;59628;1408.1973275233224;1428.357239242345;1429.0996672096512;1442.5455981365835;1456.6649670575828;29070.854645726635;29077.7092229075;29112.417751645273;29969.70030299669;30004.849975750854;50285.49714502808;50787.746061269456;50815;50869.982600347044;50987.72530686762;429383;441636;442440;443430;446683;25942.610860836725;26304.34239144037;26326.60510092312;26343.20970370892;26377.340566485993;44723.77638111789;44917.77541112273;45200.86997824949;45988.770056150846;47292.81767955691;30713.078607641808;36310.455343170404;38298.80850595729;38705.225895481366;39153.80423097866;37270.25459490849;37472.063198420256;37737.62262377341;38170.236595267386;38395.040123997125;33390;37296.25407491889;37843.243135137694;38209;39627.2074558513;32085.67914320827;32807.015789526355;33081.83459082689;33542.99371018873;33861.492077618364;24514;24904.875475622506;25176;25228.859735628055;25278.494430111663 02:01:36 INFO - PROCESS | 2252 | |2;jquery.html;340.6406241415307;383.63937898375536;388.7181793199929;403.76581582682047;404.4398508066327;11601.767964640701;19742.210311587518;21072.051757670903;21774.564508709835;21784.78215217847;21097;21650.3504894853;21759.347219583397;22108.336749897488;22476.775232247674;6017.06735456005;6644.235912870022;6804.183497980224;6840.110785597865;6850.383465488105;4590.242609969351;7452.329290363866;7867.881981770278;7885.329746971514;7970.6413211405625;7119.181294151187;7553.660085296175;7610.277023682741;7967.96016019922;7968.800779980489;7271.963640181818;7569.394448444111;7601.087869455645;7645.350145237661;7715.845683086335;7348.559086454829;7376.336129748348;7385.4830161888685;7385.9261407385775;7402.740904068386;27005.729942700516;27198.456030879468;27797.16608501748;29102;29237.26906827325;2155.084089262073;2199.8350123740624;2227.042371780134;2248.1232319395285;2382.6187809950325;15240.46658366963;16389.426370077108;16428.589285267844;16607.50177494677;16721.247543860554;1809.9638007239914;1885.4060970794155;1885.6982882738696;1975.2987689370354;2242.820574354048;111935.88064119336;112293;122470.38764806208;130731;135815.32092339476;20837.583248335253;21215.575688486144;21223.575528489342;21569.89215053946;21805.781942180693;29008.854955725088;29489.262768430533;34026.31947361039;34362.656373436446;35181.472277915855;10912.399818010086;11011.61459348919;11326.207165498336;11333.829992550118;11454.312741235553;7477.88783168242;9257.028012058772;9264.53677316145;9336.90663093374;9384.577694003787;3718.2191739734417;3941.8029098544957;10053;10135.949320253352;10283.177345812466;1679.7228457304652;1940.146335612326;1987.592543528573;1989.6816509358723;1992.501874531367;845.8350621628846;927.3045216087934;959.7312752429331;974.1622204903778;992.6301703649062;20277.986100694907;21285.255016074363;21361.78638213629;21411.785882141292;21451.820149891595;867.5055218525506;925.2690374603987;962.0139357158828;976.2239019979133;1009.5154325923451;11101.222914395914;11298.830517542243;11421.37182454973;11429.257098288585;11473.483693233824;524457;529399;535883;536697;537067.3146634242;118986.4050679741;120021;123799;124038;124707.3764631171;1019.5259204469957;1026.0560284538094;1045.4981608827804;1066.4720963123305;1070.7751372211924;18710.345137920107;18914.43256702301;19036.33372831944;19046;19275.61448771045;39437.80281098576;51893.48106518886;53797.46202538081;53908;54160.729196353765;60460.69769651123;73686.89469658067;76759.23240767521;80603;83218;7077.5753454792875;7514.812129696801;7584.924150758422;7814.374850012099;8520.74437766868;9083.18251357381;9086.54567271647;12386.938065309616;12552.372381380712;13142.342882855628;10936.289141205796;10966.890331096587;11013.063889569476;11053.889461105286;11224.55101795919;10735.785284294427;10773.461326933466;10869.728256793644;10912.399818010086;11093.722656933318;11089.27919685218;11191.88808111941;11288.548458061585;11449.31304121722;11632.53469861196;8059.677612895419;8130.430869839054;8332.125126861592;8333.874991875005;8500.362472814688;9428.669996550088;10686.305390149926;10839.078678312431;10962;10986.560537578407 02:01:36 INFO - PROCESS | 2252 | |3;mootools.html;1543.6372452473663;1593.3069114935001;1775.5472354549595;1782.5721826761574;1897.1936926806104;8117.512949223051;8717.084706105847;8733.126687331263;9228.723138305853;9236.722898313054;7435.702571897117;8841.955790221056;9001.819963600725;9009.279257659387;9015.504147271897;2369.9289021329387;2375.489269806991;2405.687260656121;2408.915687950922;2409.903603855844;2379.3100000999775;2398.604230301998;2404.3147702904675;2404.7354790972977;2409.024345140221;2002.6094911492264;2348.4598542335275;2371.991903441039;2384.3204686664303;2409.7108346998384;2368.6683864259016;2373.9762602373926;2379.119725701493;2384.5350156719446;2389.928302150938;2277.6241920083166;2329.0218108394515;2359.834811563191;2361.374235827507;2385.7017872765905;9010.909890901139;9093.681721139728;9122.635094596208;9134.31492638048;9135.177833994905;5314.973425132888;5378.220158077096;5513.228148059275;5517.751701173457;5519.530839878616;3523.066387407339;3541.2386336937684;3591.5510561179854;3639.70882329413;3650.7079433645245;848.3213429256632;990.2028866762254;1027.6505987964053;1035.2804800663541;1050.0864248104199;10997.725056873642;11826.822597660956;12187.93906030473;12216.961558267558;12224.816627750592;4399.714018588781;4743.691660042086;4814.807407703653;4852.223644216909;4916.778744956486;10180.898191018143;10199.13307368867;10217.438040907822;10231.334963227366;10295.588176473007;5384.326959130109;5386.353637563518;5392.622516423813;5399.406065332811;5483.972580137074;4468.687191896536;4801.879953001203;4883.121038213085;4907.411110666742;4996.975015124901;4078.260912322816;4089.7546147230664;4097.180563887235;4100.5899410058755;4152.875413737593;1222.9755404891853;1226.1600803449664;1240.7890658588062;1244.4773195257833;1278.5780692371495;897.9865302020474;913.5980168725739;923.7505873414141;952.1335584618103;953.3755390219418;5836.066229403275;5880.470757631833;6024.698765061729;6084.330723620398;6094.329623741385;425.6679789763989;432.43999021267535;437.3898411715639;437.964962802975;454.6749074411811;3808.3716186829415;3860.749051311656;3971.4638523799495;4020.8994775130855;4066.3087275163293;10511.791144018383;10620.522076506575;10972.396518191576;10974.945125274322;10975.396353200651;9769.364991275545;10722.839157412645;10994.780104397867;11130.332180069221;11132.554697811996;8325.292350150305;8331.250187483156;8532.23209911111;8535.274501667427;8590.410774006828;5946.791862284799;6081.452669259788;6094.3600921904335;6169.81490555284;6174.413430724074;9853.605855765976;10151.543180556744;10283.94858025705;10324.48377581133;10343.534540945526;10275.383476991405;10428;10436;10453.581856725647;10527.789444211227;6001.879962400815;6871.175458944958;7479.102507699111;7596.544207347577;7616.124145723313;8552.786180345292;8563.529005904735;8573.31413486932;8649.437786543855;8670.26302764272;7443.404527637667;7603.315701586884;7629.885551716618;7695.384369250559;7704.26809453101;7417.072865891763;7498;7526.98385717943;7594.392448604209;7628.237176282328;6967.198772141269;7000.824979375353;7051.717931282691;7069.9646501767165;7085.220625731268;4342.717723347972;4827.179379505563;4915.3855768029;5071.239314102767;5079.492050795039;5016.347874776256;5047.949520504895;5098.082345177903;5118.923216151835;5132.923006154836 02:01:36 INFO - PROCESS | 2252 | |4;prototype.html;306.248160765321;313.14511383921894;317.7077089078048;318.2029017311634;326.1129727142172;4682.695624784386;5033.773480193391;5606.915896261554;5751.482366587006;5760.991826430373;5151.454563630905;5443.775150591125;5575.804846830361;5666.461686139821;5669.63147395419;3483.4948932404786;3801.334766415866;4296.849610263634;4366.628836548897;4378.1462614790125;3839.462475253467;4142;4236.597523235303;4357.2592659248;4384.408104905845;4068.7558746475306;4243.172581346638;4262.78686065697;4288.571142885718;4299.656027517791;3376.898693039213;4168.85356526956;4299.484061912591;4305.935410968838;4325.783710814447;3299.6040475143136;3317.1872891141584;3755.9436608450897;3768.4724138620613;3769.9057523561855;4525.932111018337;5184.533391994701;5187.299714538564;5410.864728381783;5448.4279150689;4598.9080218395775;4694.788734506956;4748.430188377382;4978.153713868651;4999.3000979862845;5641;5959.821205363846;6002.279726432856;6020.789272375446;6046.183765191687;4081.9183616327805;4106.568810274938;4126.773027483488;4136;4145.97927010363;5635;6289.7169627366875;6296.937030629727;6321.936780632181;6391;3522.647735226457;3860.864869729602;3976.701747368959;4162.666986641061;4190.224808410393;3600.279944011208;3810.542734871833;4231.809568569422;4238.491381034234;4269.274223382033;4587.3807036050375;4957.256411538298;4965.280034394992;4980.302757613937;4992.301077849104;5147.742612869341;5383.67697938125;5854.736536855852;6057.727402266909;6079.8480037998515;1419.6237996930822;1450.6808502129516;1454.1929229277641;1454.8326942401548;1464.414234306286;786.9062003814702;864.4769914201943;868.7263511993829;884.6549845560237;906.977325566866;4731.361266229083;4762.285657151455;4763.333133361332;4771.210795951518;4793.18515852299;4372.344148377769;4804.42346918365;5128.743562821844;5299.258103865462;5306.8407947761625;3730.862087063472;4488.326750987378;4665.86002419928;4786.904261914742;4791.425028996473;2424.2484829702494;2474.925752227436;2711.891524339044;2711.945761084767;2801.957970630442;5107.795688172506;5201.479852014768;5244.964833759557;5500.7524661390335;5507.476789704971;4537.1833070047705;4974.77613507393;5005.499450054966;5092.439831618519;5200.60772044856;4794.496577859344;5196.428392876781;5279.736013199248;5528.391876893538;5530;4586.216371493598;4777.160793094614;4889.750714671149;5072.923906141485;5325.387580428145;4108.77401742895;4586.4266966629175;5216.817411390583;5266.2363957225225;5276.654453114431;4453.621442177451;4976.0794253063295;5362.892742145214;5407.756650950795;5518.641288316247;2997.8201307921595;3235.385276797401;3453.912017714412;3633.983139357682;3667.7615954962844;4710.623150148049;5024.522670346311;5196.32447781786;5288.312519372457;6152.969235153795;5290.65610735301;5496.532794712494;5936.525077993837;5951.16683664296;5974.970125149346;5523.171524271392;5957.889056550966;5982.760689572542;5992.191054207626;6072.180255665428;4870.70926204549;5358.4105748366865;5608.354569529838;5864.44287792659;5888.764449422147;5405.378381486022;5959.722152815847;5973.283206015306;5986.461218490357;5988.640681559121;5058.215976523645;5123.308353372247;5211.687298762086;5882.970585147047;5914.687774845928 02:01:36 INFO - PROCESS | 2252 | |5;yui.html;718.5257729898268;729.5367441674535;737.8487410080934;756.2022066719612;786.5791801386252;16733.247003884822;17172.313107475686;17336.653266934685;17338.65322693547;17375.957442553456;13613.523526676569;16414.425495107673;16512.926659767138;16528.504144875675;16556.172191390444;662.9370209830058;702.5222848463043;891.5987805487524;897.4121950122681;897.8024834536424;876.9605367758467;883.5228976352762;884.0938038510513;893.4371346052011;895.0646574329841;789.7986013566532;955.1594596754846;957.6983250276176;959.3140904253457;959.4339339789523;948.8054948735493;949.6438835436711;951.6003278622995;953.0279115302422;953.6709835106877;666.7533012785301;690.2338404371144;693.5561240805888;693.8057343943703;693.8577591593711;15194.012389194668;15444.922775386163;15447.845521544754;15451.61370965735;15495.147766872817;12381.814272785916;12441.813372799415;12485.938695210918;12490.687732806662;12759.936200319033;8468.957655211747;9790.265730070274;10144.492775361203;10326.58693652253;11004.779904401867;3452.033430639425;3459.5502584664086;3483.9651603483894;3496.702780263681;3562.3561461331647;84614.15385846185;85073.57463212706;85451;85627.99999999937;86171;9353.11145441182;9408.529573521433;9498.620055197853;9692.321537492307;9807.362521436084;21682.24112156067;21800.34598962034;21808.45478863041;21907.233246836604;21963.670544941837;7274.745383911538;7334.523255988343;7344.485885988036;7554.471187016856;7607.16321204667;3937.330653788863;4017.6584990276156;4018.7789671568344;4020.6582440492302;4063.045184381668;3450.0512359100944;3471.8437670304393;3473.992542162795;3476.8261586920553;3567.9286414272087;538.7144813248984;619.6684773646077;629.1412222316505;631.5231999840091;640.3020707428832;531.3411369901295;548.7420912171273;591.257971246092;599.8020653184526;617.2222999021176;8185.549794761227;8248.381371397169;8270.214329638675;8387.329013678891;8415.957920210481;563.7942151114813;575.283771704231;590.7368899382108;596.8865915476046;618.9182342451525;3429.639887811794;3907.8046097695;3941.5664276929515;3955.3275943089743;3976.76139431635;91074.17851643062;93465.53267233621;94207;94883;95079.52460237655;68731;79533.20466795373;80315.59842200752;81164;81252.59373703212;64387;70374.99999999898;72064.63967680233;72167.91748123782;72249.6387518059;8309.667613295642;8325.708600198965;8328.375371847014;8330.208630180123;8341.582920853813;44418;44907.77546112379;44946;44962.77518612386;45609.47443671757;58419.70790146022;60763;62832.057519138165;63178.684106579174;63758.68120659367;10427.914417116759;10712.946435267773;10786.568537258421;10847.620333288298;11617.186796924134;11498.137639677225;11920.105992050458;12208.267503949792;12425.813612795997;12769.744605108031;10191.592336306465;10857.891421085687;10964;11115;11731;11172.608958686407;11180.832287515856;11273.323600583992;11523.942380288045;11632;9954.950225248827;10998;11248.78087617675;11372.54509819598;11459;9379.437233765995;9773.615919109294;9897.208223342259;9912.801743964936;10288.537015834156;6917.481188910952;9692.806143877224;9751.268654850772;9772.832823134959;10133.290669653348 02:01:36 INFO - PROCESS | 2252 | __end_tp_report 02:01:36 INFO - PROCESS | 2252 | __start_cc_report 02:01:36 INFO - PROCESS | 2252 | _x_x_mozilla_cycle_collect,415 02:01:36 INFO - PROCESS | 2252 | __end_cc_report 02:01:36 INFO - PROCESS | 2252 | __startTimestamp1464080496283__endTimestamp 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | ------- Summary: start ------- 02:01:36 INFO - PROCESS | 2252 | Number of tests: 6 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | [#0] dojo.html Cycles:165 Average:5831.26 Median:6107.36 stddev:801.38 (13.1%) stddev-sans-first:767.54 02:01:36 INFO - PROCESS | 2252 | Values: 2791.6 3529.4 3534.1 3561.0 3563.2 5909.1 5993.7 6041.1 6050.2 6052.5 5796.6 5836.2 5946.9 5955.4 6053.2 4878.3 5006.5 5317.5 5499.4 5509.8 196.6 4929.5 5328.7 5434.8 5467.8 5410.6 5434.3 5441.6 5452.3 5462.9 5457.3 5477.7 5482.5 5501.9 5539.9 5275.0 5335.0 5358.7 5404.3 5434.4 5672.5 5770.6 5824.3 5853.6 5859.4 6236.7 6249.8 6313.3 6316.5 6334.3 6230.7 6272.7 6287.8 6356.3 6363.3 6095.5 6098.2 6099.0 6107.4 6117.3 6250.5 6340.2 6488.5 6505.3 6519.2 6235.2 6235.4 6292.1 6293.0 6295.5 6297.8 6304.2 6377.1 6381.5 6401.3 5215.2 6231.4 6232.8 6292.5 6295.6 5162.4 5294.3 6197.9 6278.8 6305.3 5066.8 5091.1 5101.1 5102.2 5162.0 3909.1 4450.4 4474.8 4502.4 4560.9 5869.4 5915.0 5961.6 6006.0 6048.1 6275.1 6307.1 6312.9 6321.1 6322.5 4942.8 5820.5 5858.6 5930.0 6005.3 5479.2 5512.4 5530.9 5536.7 5545.3 6538.7 6549.1 6549.3 6549.8 6561.9 6228.5 6259.1 6322.6 6324.8 6330.1 6523.8 6555.1 6559.3 6561.3 6577.9 5516.1 6197.3 6317.7 6320.5 6338.4 6269.3 6292.7 6293.0 6332.7 6339.9 6140.8 6245.5 6313.0 6321.4 6343.0 6121.3 6146.8 6288.8 6337.5 6351.4 6097.8 6100.4 6172.9 6222.1 6229.2 6122.2 6242.1 6297.3 6301.1 6315.1 6024.4 6052.1 6057.2 6172.7 6177.9 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | [#1] ext.html Cycles:180 Average:59736.23 Median:37283.25 stddev:127671.51 (342.4%) stddev-sans-first:127952.20 02:01:36 INFO - PROCESS | 2252 | Values: 500.9 521.8 527.6 535.6 552.5 31289.4 40245.8 41169.8 41186.0 41186.8 41814.4 41944.0 42743.4 42811.6 42863.0 9478.8 9623.0 9672.0 9691.0 9708.9 9597.9 9690.6 9704.6 9705.3 9717.3 9671.8 9731.3 9743.6 9744.2 9764.3 9628.3 9674.6 9705.9 9706.4 9711.6 9553.0 9560.8 9657.9 9677.0 9680.1 35020.0 38776.6 38872.2 38991.8 39255.4 2717.8 2953.5 3047.1 3236.3 3269.9 26288.7 27253.3 28024.2 28686.9 28809.0 2190.5 2618.1 2624.5 2714.2 2721.0 321263.0 396577.0 405831.0 408069.0 409130.0 33021.7 33599.8 33665.5 33719.3 34659.3 35213.8 35608.0 35632.8 35696.5 35817.6 15175.3 15246.8 15288.9 15326.6 15362.8 12813.6 12875.9 12881.5 12891.9 12905.9 7821.5 7822.8 7844.2 7878.3 7890.4 3277.7 3285.3 3291.9 3293.2 3317.8 977.5 1032.1 1039.1 1049.8 1114.4 23408.4 28422.7 29505.7 29576.9 30015.4 1051.7 1074.1 1084.5 1090.2 1100.0 12926.2 14574.9 15135.8 15180.6 15407.0 570717.0 582056.0 585413.0 586243.0 592224.0 58995.8 59417.0 59463.0 59627.1 59628.0 1408.2 1428.4 1429.1 1442.5 1456.7 29070.9 29077.7 29112.4 29969.7 30004.8 50285.5 50787.7 50815.0 50870.0 50987.7 429383.0 441636.0 442440.0 443430.0 446683.0 25942.6 26304.3 26326.6 26343.2 26377.3 44723.8 44917.8 45200.9 45988.8 47292.8 30713.1 36310.5 38298.8 38705.2 39153.8 37270.3 37472.1 37737.6 38170.2 38395.0 33390.0 37296.3 37843.2 38209.0 39627.2 32085.7 32807.0 33081.8 33543.0 33861.5 24514.0 24904.9 25176.0 25228.9 25278.5 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | [#2] jquery.html Cycles:180 Average:34402.33 Median:12167.69 stddev:89258.83 (733.6%) stddev-sans-first:89472.58 02:01:36 INFO - PROCESS | 2252 | Values: 340.6 383.6 388.7 403.8 404.4 11601.8 19742.2 21072.1 21774.6 21784.8 21097.0 21650.4 21759.3 22108.3 22476.8 6017.1 6644.2 6804.2 6840.1 6850.4 4590.2 7452.3 7867.9 7885.3 7970.6 7119.2 7553.7 7610.3 7968.0 7968.8 7272.0 7569.4 7601.1 7645.4 7715.8 7348.6 7376.3 7385.5 7385.9 7402.7 27005.7 27198.5 27797.2 29102.0 29237.3 2155.1 2199.8 2227.0 2248.1 2382.6 15240.5 16389.4 16428.6 16607.5 16721.2 1810.0 1885.4 1885.7 1975.3 2242.8 111935.9 112293.0 122470.4 130731.0 135815.3 20837.6 21215.6 21223.6 21569.9 21805.8 29008.9 29489.3 34026.3 34362.7 35181.5 10912.4 11011.6 11326.2 11333.8 11454.3 7477.9 9257.0 9264.5 9336.9 9384.6 3718.2 3941.8 10053.0 10135.9 10283.2 1679.7 1940.1 1987.6 1989.7 1992.5 845.8 927.3 959.7 974.2 992.6 20278.0 21285.3 21361.8 21411.8 21451.8 867.5 925.3 962.0 976.2 1009.5 11101.2 11298.8 11421.4 11429.3 11473.5 524457.0 529399.0 535883.0 536697.0 537067.3 118986.4 120021.0 123799.0 124038.0 124707.4 1019.5 1026.1 1045.5 1066.5 1070.8 18710.3 18914.4 19036.3 19046.0 19275.6 39437.8 51893.5 53797.5 53908.0 54160.7 60460.7 73686.9 76759.2 80603.0 83218.0 7077.6 7514.8 7584.9 7814.4 8520.7 9083.2 9086.5 12386.9 12552.4 13142.3 10936.3 10966.9 11013.1 11053.9 11224.6 10735.8 10773.5 10869.7 10912.4 11093.7 11089.3 11191.9 11288.5 11449.3 11632.5 8059.7 8130.4 8332.1 8333.9 8500.4 9428.7 10686.3 10839.1 10962.0 10986.6 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | [#3] mootools.html Cycles:180 Average:5789.36 Median:4808.34 stddev:3294.33 (68.5%) stddev-sans-first:3288.12 02:01:36 INFO - PROCESS | 2252 | Values: 1543.6 1593.3 1775.5 1782.6 1897.2 8117.5 8717.1 8733.1 9228.7 9236.7 7435.7 8842.0 9001.8 9009.3 9015.5 2369.9 2375.5 2405.7 2408.9 2409.9 2379.3 2398.6 2404.3 2404.7 2409.0 2002.6 2348.5 2372.0 2384.3 2409.7 2368.7 2374.0 2379.1 2384.5 2389.9 2277.6 2329.0 2359.8 2361.4 2385.7 9010.9 9093.7 9122.6 9134.3 9135.2 5315.0 5378.2 5513.2 5517.8 5519.5 3523.1 3541.2 3591.6 3639.7 3650.7 848.3 990.2 1027.7 1035.3 1050.1 10997.7 11826.8 12187.9 12217.0 12224.8 4399.7 4743.7 4814.8 4852.2 4916.8 10180.9 10199.1 10217.4 10231.3 10295.6 5384.3 5386.4 5392.6 5399.4 5484.0 4468.7 4801.9 4883.1 4907.4 4997.0 4078.3 4089.8 4097.2 4100.6 4152.9 1223.0 1226.2 1240.8 1244.5 1278.6 898.0 913.6 923.8 952.1 953.4 5836.1 5880.5 6024.7 6084.3 6094.3 425.7 432.4 437.4 438.0 454.7 3808.4 3860.7 3971.5 4020.9 4066.3 10511.8 10620.5 10972.4 10974.9 10975.4 9769.4 10722.8 10994.8 11130.3 11132.6 8325.3 8331.3 8532.2 8535.3 8590.4 5946.8 6081.5 6094.4 6169.8 6174.4 9853.6 10151.5 10283.9 10324.5 10343.5 10275.4 10428.0 10436.0 10453.6 10527.8 6001.9 6871.2 7479.1 7596.5 7616.1 8552.8 8563.5 8573.3 8649.4 8670.3 7443.4 7603.3 7629.9 7695.4 7704.3 7417.1 7498.0 7527.0 7594.4 7628.2 6967.2 7000.8 7051.7 7070.0 7085.2 4342.7 4827.2 4915.4 5071.2 5079.5 5016.3 5047.9 5098.1 5118.9 5132.9 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | [#4] prototype.html Cycles:180 Average:4525.83 Median:4975.43 stddev:1385.41 (27.8%) stddev-sans-first:1352.61 02:01:36 INFO - PROCESS | 2252 | Values: 306.2 313.1 317.7 318.2 326.1 4682.7 5033.8 5606.9 5751.5 5761.0 5151.5 5443.8 5575.8 5666.5 5669.6 3483.5 3801.3 4296.8 4366.6 4378.1 3839.5 4142.0 4236.6 4357.3 4384.4 4068.8 4243.2 4262.8 4288.6 4299.7 3376.9 4168.9 4299.5 4305.9 4325.8 3299.6 3317.2 3755.9 3768.5 3769.9 4525.9 5184.5 5187.3 5410.9 5448.4 4598.9 4694.8 4748.4 4978.2 4999.3 5641.0 5959.8 6002.3 6020.8 6046.2 4081.9 4106.6 4126.8 4136.0 4146.0 5635.0 6289.7 6296.9 6321.9 6391.0 3522.6 3860.9 3976.7 4162.7 4190.2 3600.3 3810.5 4231.8 4238.5 4269.3 4587.4 4957.3 4965.3 4980.3 4992.3 5147.7 5383.7 5854.7 6057.7 6079.8 1419.6 1450.7 1454.2 1454.8 1464.4 786.9 864.5 868.7 884.7 907.0 4731.4 4762.3 4763.3 4771.2 4793.2 4372.3 4804.4 5128.7 5299.3 5306.8 3730.9 4488.3 4665.9 4786.9 4791.4 2424.2 2474.9 2711.9 2711.9 2802.0 5107.8 5201.5 5245.0 5500.8 5507.5 4537.2 4974.8 5005.5 5092.4 5200.6 4794.5 5196.4 5279.7 5528.4 5530.0 4586.2 4777.2 4889.8 5072.9 5325.4 4108.8 4586.4 5216.8 5266.2 5276.7 4453.6 4976.1 5362.9 5407.8 5518.6 2997.8 3235.4 3453.9 3634.0 3667.8 4710.6 5024.5 5196.3 5288.3 6153.0 5290.7 5496.5 5936.5 5951.2 5975.0 5523.2 5957.9 5982.8 5992.2 6072.2 4870.7 5358.4 5608.4 5864.4 5888.8 5405.4 5959.7 5973.3 5986.5 5988.6 5058.2 5123.3 5211.7 5883.0 5914.7 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - PROCESS | 2252 | [#5] yui.html Cycles:180 Average:18273.73 Median:618.07 stddev:25715.20 (4160.6%) stddev-sans-first:25753.56 02:01:36 INFO - PROCESS | 2252 | Values: 718.5 729.5 737.8 756.2 786.6 16733.2 17172.3 17336.7 17338.7 17376.0 13613.5 16414.4 16512.9 16528.5 16556.2 662.9 702.5 891.6 897.4 897.8 877.0 883.5 884.1 893.4 895.1 789.8 955.2 957.7 959.3 959.4 948.8 949.6 951.6 953.0 953.7 666.8 690.2 693.6 693.8 693.9 15194.0 15444.9 15447.8 15451.6 15495.1 12381.8 12441.8 12485.9 12490.7 12759.9 8469.0 9790.3 10144.5 10326.6 11004.8 3452.0 3459.6 3484.0 3496.7 3562.4 84614.2 85073.6 85451.0 85628.0 86171.0 9353.1 9408.5 9498.6 9692.3 9807.4 21682.2 21800.3 21808.5 21907.2 21963.7 7274.7 7334.5 7344.5 7554.5 7607.2 3937.3 4017.7 4018.8 4020.7 4063.0 3450.1 3471.8 3474.0 3476.8 3567.9 538.7 619.7 629.1 631.5 640.3 531.3 548.7 591.3 599.8 617.2 8185.5 8248.4 8270.2 8387.3 8416.0 563.8 575.3 590.7 596.9 618.9 3429.6 3907.8 3941.6 3955.3 3976.8 91074.2 93465.5 94207.0 94883.0 95079.5 68731.0 79533.2 80315.6 81164.0 81252.6 64387.0 70375.0 72064.6 72167.9 72249.6 8309.7 8325.7 8328.4 8330.2 8341.6 44418.0 44907.8 44946.0 44962.8 45609.5 58419.7 60763.0 62832.1 63178.7 63758.7 10427.9 10712.9 10786.6 10847.6 11617.2 11498.1 11920.1 12208.3 12425.8 12769.7 10191.6 10857.9 10964.0 11115.0 11731.0 11172.6 11180.8 11273.3 11523.9 11632.0 9955.0 10998.0 11248.8 11372.5 11459.0 9379.4 9773.6 9897.2 9912.8 10288.5 6917.5 9692.8 9751.3 9772.8 10133.3 02:01:36 INFO - PROCESS | 2252 | -------- Summary: end -------- 02:01:36 INFO - PROCESS | 2252 | 02:01:36 INFO - TEST-INFO | 2252: exit 0 02:01:36 INFO - TEST-OK | dromaeo_css | took 1111635ms 02:01:36 INFO - TEST-START | kraken 02:01:36 INFO - operating with platform_type : mac_ 02:01:36 INFO - Initialising browser for kraken test... 02:01:36 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpVkTPZn/profile http://localhost:52582/getInfo.html) 02:01:39 INFO - PROCESS | 2256 | __metrics Screen width/height:1600/1200 02:01:39 INFO - PROCESS | 2256 | colorDepth:24 02:01:39 INFO - PROCESS | 2256 | Browser inner width/height: 1024/689 02:01:39 INFO - PROCESS | 2256 | __metrics 02:01:39 ERROR - PROCESS | 2256 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:01:40 INFO - TEST-INFO | 2256: exit 0 02:01:40 INFO - Browser initialized. 02:01:40 INFO - Running cycle 1/1 for kraken test... 02:01:40 INFO - TEST-INFO | started process 2259 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpVkTPZn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:01:52 INFO - PROCESS | 2259 | RSS: Main: 249921536 02:01:52 INFO - PROCESS | 2259 | 02:01:55 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52582/tests/kraken/driver.html?testName=audio-beat-detection) 02:01:56 INFO - PROCESS | 2259 | RSS: Main: 261869568 02:01:56 INFO - PROCESS | 2259 | 02:01:59 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52582/tests/kraken/driver.html?testName=audio-dft) 02:01:59 INFO - PROCESS | 2259 | RSS: Main: 266539008 02:01:59 INFO - PROCESS | 2259 | 02:02:03 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52582/tests/kraken/driver.html?testName=audio-fft) 02:02:03 INFO - PROCESS | 2259 | RSS: Main: 267468800 02:02:03 INFO - PROCESS | 2259 | 02:02:05 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52582/tests/kraken/driver.html?testName=audio-oscillator) 02:02:06 INFO - PROCESS | 2259 | RSS: Main: 268099584 02:02:06 INFO - PROCESS | 2259 | 02:02:08 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52582/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:02:09 INFO - PROCESS | 2259 | RSS: Main: 268734464 02:02:09 INFO - PROCESS | 2259 | 02:02:12 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52582/tests/kraken/driver.html?testName=imaging-darkroom) 02:02:13 INFO - PROCESS | 2259 | RSS: Main: 268509184 02:02:13 INFO - PROCESS | 2259 | 02:02:16 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52582/tests/kraken/driver.html?testName=imaging-desaturate) 02:02:16 INFO - PROCESS | 2259 | RSS: Main: 269168640 02:02:16 INFO - PROCESS | 2259 | 02:02:19 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52582/tests/kraken/driver.html?testName=json-parse-financial) 02:02:20 INFO - PROCESS | 2259 | RSS: Main: 268787712 02:02:20 INFO - PROCESS | 2259 | 02:02:22 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52582/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:02:22 INFO - PROCESS | 2259 | RSS: Main: 268554240 02:02:22 INFO - PROCESS | 2259 | 02:02:24 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:02:25 INFO - PROCESS | 2259 | RSS: Main: 268505088 02:02:25 INFO - PROCESS | 2259 | 02:02:27 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:02:28 INFO - PROCESS | 2259 | RSS: Main: 268627968 02:02:28 INFO - PROCESS | 2259 | 02:02:31 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:02:32 INFO - PROCESS | 2259 | RSS: Main: 268677120 02:02:32 INFO - PROCESS | 2259 | 02:02:35 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:02:36 INFO - PROCESS | 2259 | RSS: Main: 268627968 02:02:36 INFO - PROCESS | 2259 | 02:02:37 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:52582/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52582/tests/kraken/driver.html?testName=ai-astar) 02:02:38 INFO - PROCESS | 2259 | RSS: Main: 271568896 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | __start_tp_report 02:02:38 INFO - PROCESS | 2259 | _x_x_mozilla_page_load 02:02:38 INFO - PROCESS | 2259 | _x_x_mozilla_page_load_details 02:02:38 INFO - PROCESS | 2259 | |i|pagename|runs| 02:02:38 INFO - PROCESS | 2259 | |0;ai-astar;115;110;115;115;118;116;140;158;117;114 02:02:38 INFO - PROCESS | 2259 | |1;audio-beat-detection;151;119;145;202;115;175;161;146;194;149 02:02:38 INFO - PROCESS | 2259 | |2;audio-dft;197;208;214;224;202;202;199;200;199;201 02:02:38 INFO - PROCESS | 2259 | |3;audio-fft;71;92;76;182;84;80;86;97;130;58 02:02:38 INFO - PROCESS | 2259 | |4;audio-oscillator;79;78;146;77;79;89;123;81;82;125 02:02:38 INFO - PROCESS | 2259 | |5;imaging-gaussian-blur;90;88;90;106;80;118;135;110;90;88 02:02:38 INFO - PROCESS | 2259 | |6;imaging-darkroom;96;95;118;113;97;96;97;97;95;117 02:02:38 INFO - PROCESS | 2259 | |7;imaging-desaturate;143;142;91;92;92;91;92;89;90;92 02:02:38 INFO - PROCESS | 2259 | |8;json-parse-financial;57;61;58;60;61;69;88;171;52;51 02:02:38 INFO - PROCESS | 2259 | |9;json-stringify-tinderbox;69;69;74;76;76;73;71;70;72;73 02:02:38 INFO - PROCESS | 2259 | |10;stanford-crypto-aes;87;85;85;89;91;101;79;80;78;99 02:02:38 INFO - PROCESS | 2259 | |11;stanford-crypto-ccm;153;143;140;157;140;153;146;157;253;156 02:02:38 INFO - PROCESS | 2259 | |12;stanford-crypto-pbkdf2;169;177;235;197;171;229;174;165;168;178 02:02:38 INFO - PROCESS | 2259 | |13;stanford-crypto-sha256-iterative;66;68;69;70;73;83;83;83;89;66 02:02:38 INFO - PROCESS | 2259 | __end_tp_report 02:02:38 INFO - PROCESS | 2259 | __start_cc_report 02:02:38 INFO - PROCESS | 2259 | _x_x_mozilla_cycle_collect,1100 02:02:38 INFO - PROCESS | 2259 | __end_cc_report 02:02:38 INFO - PROCESS | 2259 | __startTimestamp1464080558104__endTimestamp 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | ------- Summary: start ------- 02:02:38 INFO - PROCESS | 2259 | Number of tests: 14 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#0] ai-astar Cycles:10 Average:121.80 Median:116.50 stddev:15.10 (13.0%) stddev-sans-first:15.81 02:02:38 INFO - PROCESS | 2259 | Values: 115.0 110.0 115.0 115.0 118.0 116.0 140.0 158.0 117.0 114.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#1] audio-beat-detection Cycles:10 Average:155.70 Median:156.00 stddev:28.46 (18.2%) stddev-sans-first:30.14 02:02:38 INFO - PROCESS | 2259 | Values: 151.0 119.0 145.0 202.0 115.0 175.0 161.0 146.0 194.0 149.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#2] audio-dft Cycles:10 Average:204.60 Median:202.00 stddev:8.46 (4.2%) stddev-sans-first:8.52 02:02:38 INFO - PROCESS | 2259 | Values: 197.0 208.0 214.0 224.0 202.0 202.0 199.0 200.0 199.0 201.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#3] audio-fft Cycles:10 Average:95.60 Median:82.00 stddev:35.80 (43.7%) stddev-sans-first:36.85 02:02:38 INFO - PROCESS | 2259 | Values: 71.0 92.0 76.0 182.0 84.0 80.0 86.0 97.0 130.0 58.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:79.00 stddev:25.39 (32.1%) stddev-sans-first:26.19 02:02:38 INFO - PROCESS | 2259 | Values: 79.0 78.0 146.0 77.0 79.0 89.0 123.0 81.0 82.0 125.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:88.00 stddev:17.23 (19.6%) stddev-sans-first:17.92 02:02:38 INFO - PROCESS | 2259 | Values: 90.0 88.0 90.0 106.0 80.0 118.0 135.0 110.0 90.0 88.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:96.00 stddev:9.70 (10.1%) stddev-sans-first:10.03 02:02:38 INFO - PROCESS | 2259 | Values: 96.0 95.0 118.0 113.0 97.0 96.0 97.0 97.0 95.0 117.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#7] imaging-desaturate Cycles:10 Average:101.40 Median:91.50 stddev:21.69 (23.7%) stddev-sans-first:16.99 02:02:38 INFO - PROCESS | 2259 | Values: 143.0 142.0 91.0 92.0 92.0 91.0 92.0 89.0 90.0 92.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#8] json-parse-financial Cycles:10 Average:72.80 Median:60.50 stddev:36.07 (59.6%) stddev-sans-first:37.80 02:02:38 INFO - PROCESS | 2259 | Values: 57.0 61.0 58.0 60.0 61.0 69.0 88.0 171.0 52.0 51.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:2.58 (3.5%) stddev-sans-first:2.45 02:02:38 INFO - PROCESS | 2259 | Values: 69.0 69.0 74.0 76.0 76.0 73.0 71.0 70.0 72.0 73.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:86.00 stddev:7.89 (9.2%) stddev-sans-first:8.37 02:02:38 INFO - PROCESS | 2259 | Values: 87.0 85.0 85.0 89.0 91.0 101.0 79.0 80.0 78.0 99.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#11] stanford-crypto-ccm Cycles:10 Average:159.80 Median:154.50 stddev:33.44 (21.6%) stddev-sans-first:35.38 02:02:38 INFO - PROCESS | 2259 | Values: 153.0 143.0 140.0 157.0 140.0 153.0 146.0 157.0 253.0 156.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:177.50 stddev:25.69 (14.5%) stddev-sans-first:26.47 02:02:38 INFO - PROCESS | 2259 | Values: 169.0 177.0 235.0 197.0 171.0 229.0 174.0 165.0 168.0 178.0 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - PROCESS | 2259 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:78.00 stddev:8.59 (11.0%) stddev-sans-first:8.47 02:02:38 INFO - PROCESS | 2259 | Values: 66.0 68.0 69.0 70.0 73.0 83.0 83.0 83.0 89.0 66.0 02:02:38 INFO - PROCESS | 2259 | -------- Summary: end -------- 02:02:38 INFO - PROCESS | 2259 | 02:02:38 INFO - TEST-INFO | 2259: exit 0 02:02:38 INFO - TEST-OK | kraken | took 62068ms 02:02:38 INFO - SUITE-END | took 1173s 02:02:39 INFO - Completed test suite (00:19:34) 02:02:39 INFO - javascript benchmark 02:02:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7209.980872885519, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2791.567307067404, 3529.4000019996597, 3534.1341371364006, 3560.964390356096, 3563.180468492245, 5909.054551271792, 5993.670348130857, 6041.1240370146415, 6050.213472248607, 6052.515798736102, 5796.594238403313, 5836.212111364965, 5946.851328716773, 5955.404459554043, 6053.152558641794, 4878.317035614999, 5006.474320196382, 5317.521423071923, 5499.3675727291275, 5509.834704958857, 196.63886431918374, 4929.457759646436, 5328.733563321838, 5434.755436005389, 5467.808626698066, 5410.56715462766, 5434.266374039494, 5441.59188060897, 5452.345718513804, 5462.918056229139, 5457.345118585756, 5477.698726570037, 5482.479164479408, 5501.944980550184, 5539.944600553983, 5274.997750427445, 5334.986352592995, 5358.705271210121, 5404.32445944257, 5434.375046869621, 5672.546196304285, 5770.596058275923, 5824.271966004249, 5853.560982926298, 5859.384764599741, 6236.719347629368, 6249.843753906143, 6313.273973493061, 6316.463100636452, 6334.334894836022, 6230.657313847737, 6272.65500397478, 6287.811365659038, 6356.269029061625, 6363.268224154235, 6095.512359011268, 6098.176746139303, 6098.993666045098, 6107.358727333655, 6117.296510901214, 6250.531210159256, 6340.239171299474, 6488.513361497907, 6505.349465053551, 6519.217693876765, 6235.189425374672, 6235.407636274546, 6292.119103325446, 6292.968535157295, 6295.464885484785, 6297.811065668037, 6304.243490781136, 6377.107204991305, 6381.457576106083, 6401.263854656681, 5215.24378965048, 6231.4391704746795, 6232.8130156095385, 6292.528060395397, 6295.622262664255, 5162.380514338303, 5294.311739473844, 6197.907031394533, 6278.811635650938, 6305.3379395163765, 5066.822661206914, 5091.109055915274, 5101.0818052750865, 5102.183650615883, 5162.045021670928, 3909.139989202371, 4450.3991961085485, 4474.798634061475, 4502.4371953505815, 4560.931586026212, 5869.354371019184, 5914.970425147847, 5961.552883533665, 6005.969970150209, 6048.0625503047095, 6275.06253225873, 6307.05394190875, 6312.936870631327, 6321.051842223703, 6322.4942004639515, 4942.752862356868, 5820.534357251409, 5858.619189752652, 5929.970350148222, 6005.279366475964, 5479.205515200353, 5512.39363669996, 5530.86172845674, 5536.695481748542, 5545.306836645419, 6538.738450461929, 6549.050387693853, 6549.312322206099, 6549.770758023542, 6561.868762624721, 6228.4705800005695, 6259.061140828912, 6322.589031712924, 6324.841878953063, 6330.082138090043, 6523.804285871432, 6555.115059466911, 6559.27847936717, 6561.278259391368, 6577.901331479936, 5516.14499752539, 6197.287311959002, 6317.747290108344, 6320.5259605528845, 6338.429541341301, 6269.310375858743, 6292.685365731787, 6293.0, 6332.715027823851, 6339.936600633934, 6140.846478838065, 6245.469135123383, 6312.968435157794, 6321.367863213826, 6343.0, 6121.2700689216845, 6146.832101900624, 6288.811335659938, 6337.4613157882095, 6351.396617321347, 6097.847553811012, 6100.429789472632, 6172.9074063889975, 6222.066689996536, 6229.221347331583, 6122.173506576733, 6242.063690446469, 6297.338779428277, 6301.117843501822, 6315.147455093504, 6024.367441418588, 6052.05922953916, 6057.1519987200945, 6172.660503672341, 6177.938220617736], "value": 5781.602700323097, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.87728506515907, 521.8434469659103, 527.6306585390228, 535.641120449299, 552.4944675621806, 31289.374212515737, 40245.79877100618, 41169.79415102928, 41186.0, 41186.76439706806, 41814.37278440826, 41944.0, 42743.3588496172, 42811.57188428115, 42863.0, 9478.76303092423, 9622.951885240564, 9671.984441633633, 9690.951545242264, 9708.90291097087, 9597.856032159523, 9690.56392462341, 9704.563294651758, 9705.320627556073, 9717.31978761487, 9671.758206044835, 9731.270154738388, 9743.561539730728, 9744.220462362993, 9764.340062189409, 9628.277879159092, 9674.56464459101, 9705.932347441776, 9706.417614943126, 9711.61153553857, 9553.0, 9560.808783824354, 9657.90342096584, 9677.0, 9680.080392362714, 35020.0, 38776.61223387758, 38872.222555549015, 38991.8050409749, 39255.41116883249, 2717.8097533172686, 2953.5422009588547, 3047.116336262481, 3236.255661197914, 3269.9045819650446, 26288.73711262882, 27253.318667033283, 28024.159275221573, 28686.85252589894, 28809.0, 2190.485235969546, 2618.0967566189634, 2624.514464824014, 2714.1586108306487, 2720.986395068032, 321263.0, 396577.0342296569, 405831.0, 408069.0, 409130.0, 33021.66978330234, 33599.83200084033, 33665.49501757476, 33719.32561348759, 34659.30681386358, 35213.82393088018, 35608.0, 35632.82183589065, 35696.46455303173, 35817.64182358195, 15175.317110729822, 15246.847531524765, 15288.923555382153, 15326.616834579225, 15362.846371536365, 12813.615591532269, 12875.871241287654, 12881.549145779853, 12891.871081289068, 12905.870941290656, 7821.530708157528, 7822.804429889185, 7844.176361482077, 7878.330341920886, 7890.447668663139, 3277.737780977516, 3285.293661862663, 3291.8518666660057, 3293.2425542125447, 3317.767756257039, 977.5454413697664, 1032.0608246495653, 1039.1028185788102, 1049.751908302542, 1114.3870871020906, 23408.414789630395, 28422.715772842006, 29505.704942950295, 29576.852115739282, 30015.399692006038, 1051.7002654243504, 1074.0923919288189, 1084.4686103809076, 1090.2095980414294, 1100.0429626225234, 12926.159799612995, 14574.854251457562, 15135.772963405558, 15180.620484487976, 15407.0, 570717.0, 582056.0, 585413.0, 586243.0, 592224.0, 58995.82008359895, 59417.0, 59463.0, 59627.105593416134, 59628.0, 1408.1973275233224, 1428.357239242345, 1429.0996672096512, 1442.5455981365835, 1456.6649670575828, 29070.854645726635, 29077.7092229075, 29112.417751645273, 29969.70030299669, 30004.849975750854, 50285.49714502808, 50787.746061269456, 50815.0, 50869.982600347044, 50987.72530686762, 429383.0, 441636.0, 442440.0, 443430.0, 446683.0, 25942.610860836725, 26304.34239144037, 26326.60510092312, 26343.20970370892, 26377.340566485993, 44723.77638111789, 44917.77541112273, 45200.86997824949, 45988.770056150846, 47292.81767955691, 30713.078607641808, 36310.455343170404, 38298.80850595729, 38705.225895481366, 39153.80423097866, 37270.25459490849, 37472.063198420256, 37737.62262377341, 38170.236595267386, 38395.040123997125, 33390.0, 37296.25407491889, 37843.243135137694, 38209.0, 39627.2074558513, 32085.67914320827, 32807.015789526355, 33081.83459082689, 33542.99371018873, 33861.492077618364, 24514.0, 24904.875475622506, 25176.0, 25228.859735628055, 25278.494430111663], "value": 17848.122623065516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.6406241415307, 383.63937898375536, 388.7181793199929, 403.76581582682047, 404.4398508066327, 11601.767964640701, 19742.210311587518, 21072.051757670903, 21774.564508709835, 21784.78215217847, 21097.0, 21650.3504894853, 21759.347219583397, 22108.336749897488, 22476.775232247674, 6017.06735456005, 6644.235912870022, 6804.183497980224, 6840.110785597865, 6850.383465488105, 4590.242609969351, 7452.329290363866, 7867.881981770278, 7885.329746971514, 7970.6413211405625, 7119.181294151187, 7553.660085296175, 7610.277023682741, 7967.96016019922, 7968.800779980489, 7271.963640181818, 7569.394448444111, 7601.087869455645, 7645.350145237661, 7715.845683086335, 7348.559086454829, 7376.336129748348, 7385.4830161888685, 7385.9261407385775, 7402.740904068386, 27005.729942700516, 27198.456030879468, 27797.16608501748, 29102.0, 29237.26906827325, 2155.084089262073, 2199.8350123740624, 2227.042371780134, 2248.1232319395285, 2382.6187809950325, 15240.46658366963, 16389.426370077108, 16428.589285267844, 16607.50177494677, 16721.247543860554, 1809.9638007239914, 1885.4060970794155, 1885.6982882738696, 1975.2987689370354, 2242.820574354048, 111935.88064119336, 112293.0, 122470.38764806208, 130731.0, 135815.32092339476, 20837.583248335253, 21215.575688486144, 21223.575528489342, 21569.89215053946, 21805.781942180693, 29008.854955725088, 29489.262768430533, 34026.31947361039, 34362.656373436446, 35181.472277915855, 10912.399818010086, 11011.61459348919, 11326.207165498336, 11333.829992550118, 11454.312741235553, 7477.88783168242, 9257.028012058772, 9264.53677316145, 9336.90663093374, 9384.577694003787, 3718.2191739734417, 3941.8029098544957, 10053.0, 10135.949320253352, 10283.177345812466, 1679.7228457304652, 1940.146335612326, 1987.592543528573, 1989.6816509358723, 1992.501874531367, 845.8350621628846, 927.3045216087934, 959.7312752429331, 974.1622204903778, 992.6301703649062, 20277.986100694907, 21285.255016074363, 21361.78638213629, 21411.785882141292, 21451.820149891595, 867.5055218525506, 925.2690374603987, 962.0139357158828, 976.2239019979133, 1009.5154325923451, 11101.222914395914, 11298.830517542243, 11421.37182454973, 11429.257098288585, 11473.483693233824, 524457.0, 529399.0, 535883.0, 536697.0, 537067.3146634242, 118986.4050679741, 120021.0, 123799.0, 124038.0, 124707.3764631171, 1019.5259204469957, 1026.0560284538094, 1045.4981608827804, 1066.4720963123305, 1070.7751372211924, 18710.345137920107, 18914.43256702301, 19036.33372831944, 19046.0, 19275.61448771045, 39437.80281098576, 51893.48106518886, 53797.46202538081, 53908.0, 54160.729196353765, 60460.69769651123, 73686.89469658067, 76759.23240767521, 80603.0, 83218.0, 7077.5753454792875, 7514.812129696801, 7584.924150758422, 7814.374850012099, 8520.74437766868, 9083.18251357381, 9086.54567271647, 12386.938065309616, 12552.372381380712, 13142.342882855628, 10936.289141205796, 10966.890331096587, 11013.063889569476, 11053.889461105286, 11224.55101795919, 10735.785284294427, 10773.461326933466, 10869.728256793644, 10912.399818010086, 11093.722656933318, 11089.27919685218, 11191.88808111941, 11288.548458061585, 11449.31304121722, 11632.53469861196, 8059.677612895419, 8130.430869839054, 8332.125126861592, 8333.874991875005, 8500.362472814688, 9428.669996550088, 10686.305390149926, 10839.078678312431, 10962.0, 10986.560537578407], "value": 10624.858880334408, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.6372452473663, 1593.3069114935001, 1775.5472354549595, 1782.5721826761574, 1897.1936926806104, 8117.512949223051, 8717.084706105847, 8733.126687331263, 9228.723138305853, 9236.722898313054, 7435.702571897117, 8841.955790221056, 9001.819963600725, 9009.279257659387, 9015.504147271897, 2369.9289021329387, 2375.489269806991, 2405.687260656121, 2408.915687950922, 2409.903603855844, 2379.3100000999775, 2398.604230301998, 2404.3147702904675, 2404.7354790972977, 2409.024345140221, 2002.6094911492264, 2348.4598542335275, 2371.991903441039, 2384.3204686664303, 2409.7108346998384, 2368.6683864259016, 2373.9762602373926, 2379.119725701493, 2384.5350156719446, 2389.928302150938, 2277.6241920083166, 2329.0218108394515, 2359.834811563191, 2361.374235827507, 2385.7017872765905, 9010.909890901139, 9093.681721139728, 9122.635094596208, 9134.31492638048, 9135.177833994905, 5314.973425132888, 5378.220158077096, 5513.228148059275, 5517.751701173457, 5519.530839878616, 3523.066387407339, 3541.2386336937684, 3591.5510561179854, 3639.70882329413, 3650.7079433645245, 848.3213429256632, 990.2028866762254, 1027.6505987964053, 1035.2804800663541, 1050.0864248104199, 10997.725056873642, 11826.822597660956, 12187.93906030473, 12216.961558267558, 12224.816627750592, 4399.714018588781, 4743.691660042086, 4814.807407703653, 4852.223644216909, 4916.778744956486, 10180.898191018143, 10199.13307368867, 10217.438040907822, 10231.334963227366, 10295.588176473007, 5384.326959130109, 5386.353637563518, 5392.622516423813, 5399.406065332811, 5483.972580137074, 4468.687191896536, 4801.879953001203, 4883.121038213085, 4907.411110666742, 4996.975015124901, 4078.260912322816, 4089.7546147230664, 4097.180563887235, 4100.5899410058755, 4152.875413737593, 1222.9755404891853, 1226.1600803449664, 1240.7890658588062, 1244.4773195257833, 1278.5780692371495, 897.9865302020474, 913.5980168725739, 923.7505873414141, 952.1335584618103, 953.3755390219418, 5836.066229403275, 5880.470757631833, 6024.698765061729, 6084.330723620398, 6094.329623741385, 425.6679789763989, 432.43999021267535, 437.3898411715639, 437.964962802975, 454.6749074411811, 3808.3716186829415, 3860.749051311656, 3971.4638523799495, 4020.8994775130855, 4066.3087275163293, 10511.791144018383, 10620.522076506575, 10972.396518191576, 10974.945125274322, 10975.396353200651, 9769.364991275545, 10722.839157412645, 10994.780104397867, 11130.332180069221, 11132.554697811996, 8325.292350150305, 8331.250187483156, 8532.23209911111, 8535.274501667427, 8590.410774006828, 5946.791862284799, 6081.452669259788, 6094.3600921904335, 6169.81490555284, 6174.413430724074, 9853.605855765976, 10151.543180556744, 10283.94858025705, 10324.48377581133, 10343.534540945526, 10275.383476991405, 10428.0, 10436.0, 10453.581856725647, 10527.789444211227, 6001.879962400815, 6871.175458944958, 7479.102507699111, 7596.544207347577, 7616.124145723313, 8552.786180345292, 8563.529005904735, 8573.31413486932, 8649.437786543855, 8670.26302764272, 7443.404527637667, 7603.315701586884, 7629.885551716618, 7695.384369250559, 7704.26809453101, 7417.072865891763, 7498.0, 7526.98385717943, 7594.392448604209, 7628.237176282328, 6967.198772141269, 7000.824979375353, 7051.717931282691, 7069.9646501767165, 7085.220625731268, 4342.717723347972, 4827.179379505563, 4915.3855768029, 5071.239314102767, 5079.492050795039, 5016.347874776256, 5047.949520504895, 5098.082345177903, 5118.923216151835, 5132.923006154836], "value": 4549.594612750781, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.248160765321, 313.14511383921894, 317.7077089078048, 318.2029017311634, 326.1129727142172, 4682.695624784386, 5033.773480193391, 5606.915896261554, 5751.482366587006, 5760.991826430373, 5151.454563630905, 5443.775150591125, 5575.804846830361, 5666.461686139821, 5669.63147395419, 3483.4948932404786, 3801.334766415866, 4296.849610263634, 4366.628836548897, 4378.1462614790125, 3839.462475253467, 4142.0, 4236.597523235303, 4357.2592659248, 4384.408104905845, 4068.7558746475306, 4243.172581346638, 4262.78686065697, 4288.571142885718, 4299.656027517791, 3376.898693039213, 4168.85356526956, 4299.484061912591, 4305.935410968838, 4325.783710814447, 3299.6040475143136, 3317.1872891141584, 3755.9436608450897, 3768.4724138620613, 3769.9057523561855, 4525.932111018337, 5184.533391994701, 5187.299714538564, 5410.864728381783, 5448.4279150689, 4598.9080218395775, 4694.788734506956, 4748.430188377382, 4978.153713868651, 4999.3000979862845, 5641.0, 5959.821205363846, 6002.279726432856, 6020.789272375446, 6046.183765191687, 4081.9183616327805, 4106.568810274938, 4126.773027483488, 4136.0, 4145.97927010363, 5635.0, 6289.7169627366875, 6296.937030629727, 6321.936780632181, 6391.0, 3522.647735226457, 3860.864869729602, 3976.701747368959, 4162.666986641061, 4190.224808410393, 3600.279944011208, 3810.542734871833, 4231.809568569422, 4238.491381034234, 4269.274223382033, 4587.3807036050375, 4957.256411538298, 4965.280034394992, 4980.302757613937, 4992.301077849104, 5147.742612869341, 5383.67697938125, 5854.736536855852, 6057.727402266909, 6079.8480037998515, 1419.6237996930822, 1450.6808502129516, 1454.1929229277641, 1454.8326942401548, 1464.414234306286, 786.9062003814702, 864.4769914201943, 868.7263511993829, 884.6549845560237, 906.977325566866, 4731.361266229083, 4762.285657151455, 4763.333133361332, 4771.210795951518, 4793.18515852299, 4372.344148377769, 4804.42346918365, 5128.743562821844, 5299.258103865462, 5306.8407947761625, 3730.862087063472, 4488.326750987378, 4665.86002419928, 4786.904261914742, 4791.425028996473, 2424.2484829702494, 2474.925752227436, 2711.891524339044, 2711.945761084767, 2801.957970630442, 5107.795688172506, 5201.479852014768, 5244.964833759557, 5500.7524661390335, 5507.476789704971, 4537.1833070047705, 4974.77613507393, 5005.499450054966, 5092.439831618519, 5200.60772044856, 4794.496577859344, 5196.428392876781, 5279.736013199248, 5528.391876893538, 5530.0, 4586.216371493598, 4777.160793094614, 4889.750714671149, 5072.923906141485, 5325.387580428145, 4108.77401742895, 4586.4266966629175, 5216.817411390583, 5266.2363957225225, 5276.654453114431, 4453.621442177451, 4976.0794253063295, 5362.892742145214, 5407.756650950795, 5518.641288316247, 2997.8201307921595, 3235.385276797401, 3453.912017714412, 3633.983139357682, 3667.7615954962844, 4710.623150148049, 5024.522670346311, 5196.32447781786, 5288.312519372457, 6152.969235153795, 5290.65610735301, 5496.532794712494, 5936.525077993837, 5951.16683664296, 5974.970125149346, 5523.171524271392, 5957.889056550966, 5982.760689572542, 5992.191054207626, 6072.180255665428, 4870.70926204549, 5358.4105748366865, 5608.354569529838, 5864.44287792659, 5888.764449422147, 5405.378381486022, 5959.722152815847, 5973.283206015306, 5986.461218490357, 5988.640681559121, 5058.215976523645, 5123.308353372247, 5211.687298762086, 5882.970585147047, 5914.687774845928], "value": 4090.6513174422803, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.5257729898268, 729.5367441674535, 737.8487410080934, 756.2022066719612, 786.5791801386252, 16733.247003884822, 17172.313107475686, 17336.653266934685, 17338.65322693547, 17375.957442553456, 13613.523526676569, 16414.425495107673, 16512.926659767138, 16528.504144875675, 16556.172191390444, 662.9370209830058, 702.5222848463043, 891.5987805487524, 897.4121950122681, 897.8024834536424, 876.9605367758467, 883.5228976352762, 884.0938038510513, 893.4371346052011, 895.0646574329841, 789.7986013566532, 955.1594596754846, 957.6983250276176, 959.3140904253457, 959.4339339789523, 948.8054948735493, 949.6438835436711, 951.6003278622995, 953.0279115302422, 953.6709835106877, 666.7533012785301, 690.2338404371144, 693.5561240805888, 693.8057343943703, 693.8577591593711, 15194.012389194668, 15444.922775386163, 15447.845521544754, 15451.61370965735, 15495.147766872817, 12381.814272785916, 12441.813372799415, 12485.938695210918, 12490.687732806662, 12759.936200319033, 8468.957655211747, 9790.265730070274, 10144.492775361203, 10326.58693652253, 11004.779904401867, 3452.033430639425, 3459.5502584664086, 3483.9651603483894, 3496.702780263681, 3562.3561461331647, 84614.15385846185, 85073.57463212706, 85451.0, 85627.99999999937, 86171.0, 9353.11145441182, 9408.529573521433, 9498.620055197853, 9692.321537492307, 9807.362521436084, 21682.24112156067, 21800.34598962034, 21808.45478863041, 21907.233246836604, 21963.670544941837, 7274.745383911538, 7334.523255988343, 7344.485885988036, 7554.471187016856, 7607.16321204667, 3937.330653788863, 4017.6584990276156, 4018.7789671568344, 4020.6582440492302, 4063.045184381668, 3450.0512359100944, 3471.8437670304393, 3473.992542162795, 3476.8261586920553, 3567.9286414272087, 538.7144813248984, 619.6684773646077, 629.1412222316505, 631.5231999840091, 640.3020707428832, 531.3411369901295, 548.7420912171273, 591.257971246092, 599.8020653184526, 617.2222999021176, 8185.549794761227, 8248.381371397169, 8270.214329638675, 8387.329013678891, 8415.957920210481, 563.7942151114813, 575.283771704231, 590.7368899382108, 596.8865915476046, 618.9182342451525, 3429.639887811794, 3907.8046097695, 3941.5664276929515, 3955.3275943089743, 3976.76139431635, 91074.17851643062, 93465.53267233621, 94207.0, 94883.0, 95079.52460237655, 68731.0, 79533.20466795373, 80315.59842200752, 81164.0, 81252.59373703212, 64387.0, 70374.99999999898, 72064.63967680233, 72167.91748123782, 72249.6387518059, 8309.667613295642, 8325.708600198965, 8328.375371847014, 8330.208630180123, 8341.582920853813, 44418.0, 44907.77546112379, 44946.0, 44962.77518612386, 45609.47443671757, 58419.70790146022, 60763.0, 62832.057519138165, 63178.684106579174, 63758.68120659367, 10427.914417116759, 10712.946435267773, 10786.568537258421, 10847.620333288298, 11617.186796924134, 11498.137639677225, 11920.105992050458, 12208.267503949792, 12425.813612795997, 12769.744605108031, 10191.592336306465, 10857.891421085687, 10964.0, 11115.0, 11731.0, 11172.608958686407, 11180.832287515856, 11273.323600583992, 11523.942380288045, 11632.0, 9954.950225248827, 10998.0, 11248.78087617675, 11372.54509819598, 11459.0, 9379.437233765995, 9773.615919109294, 9897.208223342259, 9912.801743964936, 10288.537015834156, 6917.481188910952, 9692.806143877224, 9751.268654850772, 9772.832823134959, 10133.290669653348], "value": 6883.858591482446, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1630.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 115.0, 115.0, 118.0, 116.0, 140.0, 158.0, 117.0, 114.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 145.0, 202.0, 115.0, 175.0, 161.0, 146.0, 194.0, 149.0], "value": 155.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 208.0, 214.0, 224.0, 202.0, 202.0, 199.0, 200.0, 199.0, 201.0], "value": 204.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 92.0, 76.0, 182.0, 84.0, 80.0, 86.0, 97.0, 130.0, 58.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 146.0, 77.0, 79.0, 89.0, 123.0, 81.0, 82.0, 125.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 106.0, 80.0, 118.0, 135.0, 110.0, 90.0, 88.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 118.0, 113.0, 97.0, 96.0, 97.0, 97.0, 95.0, 117.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 91.0, 92.0, 92.0, 91.0, 92.0, 89.0, 90.0, 92.0], "value": 101.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 58.0, 60.0, 61.0, 69.0, 88.0, 171.0, 52.0, 51.0], "value": 72.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 74.0, 76.0, 76.0, 73.0, 71.0, 70.0, 72.0, 73.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 89.0, 91.0, 101.0, 79.0, 80.0, 78.0, 99.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 143.0, 140.0, 157.0, 140.0, 153.0, 146.0, 157.0, 253.0, 156.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 177.0, 235.0, 197.0, 171.0, 229.0, 174.0, 165.0, 168.0, 178.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 70.0, 73.0, 83.0, 83.0, 83.0, 89.0, 66.0], "value": 75.0, "unit": "score"}]}]} 02:02:39 INFO - Return code: 0 02:02:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:02:39 ERROR - # TBPL SUCCESS # 02:02:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:02:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:02:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:02:39 INFO - 2016-05-24 02:02:39.433 screenresolution[2264:23548] starting screenresolution argv=screenresolution get 02:02:39 INFO - 2016-05-24 02:02:39.436 screenresolution[2264:23548] Display 0: 1600x1200x32@60 02:02:39 INFO - 2016-05-24 02:02:39.442 screenresolution[2265:23551] starting screenresolution argv=screenresolution list 02:02:39 INFO - Available Modes on Display 0 02:02:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:02:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:02:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:02:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:02:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:02:39 INFO - Intel Iris: 02:02:39 INFO - Chipset Model: Intel Iris 02:02:39 INFO - Type: GPU 02:02:39 INFO - Bus: Built-In 02:02:39 INFO - VRAM (Dynamic, Max): 1536 MB 02:02:39 INFO - Vendor: Intel (0x8086) 02:02:39 INFO - Device ID: 0x0a2e 02:02:39 INFO - Revision ID: 0x0009 02:02:39 INFO - Displays: 02:02:39 INFO - Display: 02:02:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:02:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:02:39 INFO - Display Serial Number: 1600x1200 60 02:02:39 INFO - Main Display: Yes 02:02:39 INFO - Mirror: Off 02:02:39 INFO - Online: Yes 02:02:39 INFO - Rotation: Supported 02:02:39 INFO - Return code: 0 02:02:39 INFO - Running post-action listener: _resource_record_post_action 02:02:39 INFO - ##### 02:02:39 INFO - ##### Finished run-tests step (success) 02:02:39 INFO - ##### 02:02:39 INFO - Running post-run listener: _resource_record_post_run 02:02:39 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 589824; Write bytes: 695732224; Read time: 5; Write time: 1788 02:02:39 INFO - TinderboxPrint: CPU usage
54.5% 02:02:39 INFO - TinderboxPrint: I/O read bytes / time
589,824 / 5 02:02:39 INFO - TinderboxPrint: I/O write bytes / time
695,732,224 / 1,788 02:02:39 INFO - TinderboxPrint: CPU idle
2,201.6 (45.5%) 02:02:39 INFO - TinderboxPrint: CPU system
976.3 (20.2%) 02:02:39 INFO - TinderboxPrint: CPU user
1,663.7 (34.4%) 02:02:39 INFO - TinderboxPrint: Swap in / out
396,263,424 / 0 02:02:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 392145408; Write bytes: 386463232; Read time: 29616; Write time: 676 02:02:39 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 581632; Write bytes: 296055296; Read time: 4; Write time: 1073 02:02:40 INFO - Running post-run listener: _upload_blobber_files 02:02:40 INFO - Blob upload gear active. 02:02:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:02:40 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 02:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:02:40 INFO - (blobuploader) - INFO - Open directory for files ... 02:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:41 INFO - (blobuploader) - INFO - Done attempting. 02:02:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:02:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:42 INFO - (blobuploader) - INFO - Done attempting. 02:02:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:02:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:02:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:44 INFO - (blobuploader) - INFO - Done attempting. 02:02:44 INFO - (blobuploader) - INFO - Iteration through files over. 02:02:44 INFO - Return code: 0 02:02:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:02:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:02:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a3ae9b9fcd80cb043ec5dcd8743217301bcdf4c60b613b71f77955b716f4f601ec806963a3ddbc5e2601000095c7203c37f7cb29dae728a9f04dd14648fce81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cafb5cc2fe63cbbcf0915836308afe4aabfa65e7233720dfbc4fe2b818d1af0017e5766011c0987c0ecd893791e1158f3d3380edae7a03a603c6f1e1b8b1bd0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5260225d4e726dec0ca7a50e25bedc42ce3901b6c8abb1c284ae17c35cb42dcce57f0e5334dcdd20e49e8802d88b980ae8cc0c90c3a616f3126ac91fc426a3d1"} 02:02:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:02:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:02:44 INFO - Contents: 02:02:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a3ae9b9fcd80cb043ec5dcd8743217301bcdf4c60b613b71f77955b716f4f601ec806963a3ddbc5e2601000095c7203c37f7cb29dae728a9f04dd14648fce81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cafb5cc2fe63cbbcf0915836308afe4aabfa65e7233720dfbc4fe2b818d1af0017e5766011c0987c0ecd893791e1158f3d3380edae7a03a603c6f1e1b8b1bd0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5260225d4e726dec0ca7a50e25bedc42ce3901b6c8abb1c284ae17c35cb42dcce57f0e5334dcdd20e49e8802d88b980ae8cc0c90c3a616f3126ac91fc426a3d1"} 02:02:44 INFO - Running post-run listener: copy_logs_to_upload_dir 02:02:44 INFO - Copying logs to upload dir... 02:02:44 INFO - mkdir: /builds/slave/test/build/upload/logs 02:02:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.778752 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-24 02:02:44.621473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:44.624861) ========= 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.kFpTy9GIFh/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.1UhgazwNaB/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a3ae9b9fcd80cb043ec5dcd8743217301bcdf4c60b613b71f77955b716f4f601ec806963a3ddbc5e2601000095c7203c37f7cb29dae728a9f04dd14648fce81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cafb5cc2fe63cbbcf0915836308afe4aabfa65e7233720dfbc4fe2b818d1af0017e5766011c0987c0ecd893791e1158f3d3380edae7a03a603c6f1e1b8b1bd0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5260225d4e726dec0ca7a50e25bedc42ce3901b6c8abb1c284ae17c35cb42dcce57f0e5334dcdd20e49e8802d88b980ae8cc0c90c3a616f3126ac91fc426a3d1"} build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011160 build_url: 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a3ae9b9fcd80cb043ec5dcd8743217301bcdf4c60b613b71f77955b716f4f601ec806963a3ddbc5e2601000095c7203c37f7cb29dae728a9f04dd14648fce81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cafb5cc2fe63cbbcf0915836308afe4aabfa65e7233720dfbc4fe2b818d1af0017e5766011c0987c0ecd893791e1158f3d3380edae7a03a603c6f1e1b8b1bd0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5260225d4e726dec0ca7a50e25bedc42ce3901b6c8abb1c284ae17c35cb42dcce57f0e5334dcdd20e49e8802d88b980ae8cc0c90c3a616f3126ac91fc426a3d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:44.654873) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:44.655309) ========= 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.kFpTy9GIFh/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.1UhgazwNaB/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005138 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:44.704201) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:02:44.704540) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:02:44.704883) ========= ========= Total master_lag: 0.16 =========