builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0008 starttime: 1464745993.09 results: success (0) buildid: 20160531170040 builduid: 256b4d1e46294317b2ecd148e807de92 revision: 25321494921c824703a605127fb1f99b1faf5910 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.089516) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.089960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.090246) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.263562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.263995) ========= 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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-31 18:53:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2016-05-31 18:53:13 (15.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.598324 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.897800) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.898248) ========= 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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.028629 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:13.953233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-31 18:53:13.953590) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25321494921c824703a605127fb1f99b1faf5910 --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 25321494921c824703a605127fb1f99b1faf5910 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SmAJtC3iNA/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.JqkJD5ik0s/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-31 18:53:14,031 truncating revision to first 12 chars 2016-05-31 18:53:14,031 Setting DEBUG logging. 2016-05-31 18:53:14,031 attempt 1/10 2016-05-31 18:53:14,031 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25321494921c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 18:53:15,247 unpacking tar archive at: mozilla-central-25321494921c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.487072 ========= master_lag: 3.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-31 18:53:18.448670) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:18.448950) ========= script_repo_revision: 25321494921c824703a605127fb1f99b1faf5910 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:18.449317) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:18.449593) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 18:53:18.514888) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-05-31 18:53:18.515144) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:53:18 INFO - MultiFileLogger online at 20160531 18:53:18 in /builds/slave/test 18:53:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:53:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:53:18 INFO - {'append_to_log': False, 18:53:18 INFO - 'base_work_dir': '/builds/slave/test', 18:53:18 INFO - 'blob_upload_branch': 'Firefox', 18:53:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:53:18 INFO - 'branch': 'Firefox', 18:53:18 INFO - 'buildbot_json_path': 'buildprops.json', 18:53:18 INFO - 'config_files': ('talos/mac_config.py',), 18:53:18 INFO - 'default_actions': ('clobber', 18:53:18 INFO - 'read-buildbot-config', 18:53:18 INFO - 'download-and-extract', 18:53:18 INFO - 'populate-webroot', 18:53:18 INFO - 'create-virtualenv', 18:53:18 INFO - 'install', 18:53:18 INFO - 'run-tests'), 18:53:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:53:18 INFO - 'download_minidump_stackwalk': True, 18:53:18 INFO - 'download_symbols': 'ondemand', 18:53:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:53:18 INFO - 'tooltool.py': '/tools/tooltool.py', 18:53:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:53:18 INFO - '/tools/misc-python/virtualenv.py')}, 18:53:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:53:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:53:18 INFO - 'installer_path': 'installer.exe', 18:53:18 INFO - 'log_level': 'info', 18:53:18 INFO - 'log_name': 'talos', 18:53:18 INFO - 'log_to_console': True, 18:53:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:53:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:53:18 INFO - 'opt_config_files': (), 18:53:18 INFO - 'pip_index': False, 18:53:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:53:18 INFO - 'cmd': ('bash', 18:53:18 INFO - '-c', 18:53:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:53:18 INFO - 'enabled': True, 18:53:18 INFO - 'halt_on_failure': False, 18:53:18 INFO - 'name': 'check_screen_resolution'},), 18:53:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:53:18 INFO - 'cmd': ('bash', 18:53:18 INFO - '-c', 18:53:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:53:18 INFO - 'enabled': True, 18:53:18 INFO - 'halt_on_failure': False, 18:53:18 INFO - 'name': 'check_screen_resolution'},), 18:53:18 INFO - 'run_cmd_checks_enabled': True, 18:53:18 INFO - 'sps_profile': False, 18:53:18 INFO - 'sps_profile_interval': 0, 18:53:18 INFO - 'suite': 'dromaeojs', 18:53:18 INFO - 'system_bits': '32', 18:53:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:53:18 INFO - 'title': 't-yosemite-r7-0008', 18:53:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:53:18 INFO - 'use_talos_json': True, 18:53:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:53:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:53:18 INFO - 'work_dir': 'build'} 18:53:18 INFO - ##### 18:53:18 INFO - ##### Running clobber step. 18:53:18 INFO - ##### 18:53:18 INFO - Running pre-action listener: _resource_record_pre_action 18:53:18 INFO - Running main action method: clobber 18:53:18 INFO - rmtree: /builds/slave/test/build 18:53:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:53:20 INFO - Running post-action listener: _resource_record_post_action 18:53:20 INFO - ##### 18:53:20 INFO - ##### Finished clobber step (success) 18:53:20 INFO - ##### 18:53:20 INFO - ##### 18:53:20 INFO - ##### Running read-buildbot-config step. 18:53:20 INFO - ##### 18:53:20 INFO - Running pre-action listener: _resource_record_pre_action 18:53:20 INFO - Running main action method: read_buildbot_config 18:53:20 INFO - Using buildbot properties: 18:53:20 INFO - { 18:53:20 INFO - "project": "", 18:53:20 INFO - "product": "firefox", 18:53:20 INFO - "script_repo_revision": "production", 18:53:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:53:20 INFO - "repository": "", 18:53:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:53:20 INFO - "buildid": "20160531170040", 18:53:20 INFO - "pgo_build": "False", 18:53:20 INFO - "basedir": "/builds/slave/test", 18:53:20 INFO - "buildnumber": 258, 18:53:20 INFO - "slavename": "t-yosemite-r7-0008", 18:53:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:53:20 INFO - "platform": "yosemite_r7", 18:53:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:53:20 INFO - "branch": "mozilla-central", 18:53:20 INFO - "repo_path": "mozilla-central", 18:53:20 INFO - "revision": "25321494921c824703a605127fb1f99b1faf5910", 18:53:20 INFO - "stage_platform": "macosx64", 18:53:20 INFO - "builduid": "256b4d1e46294317b2ecd148e807de92", 18:53:20 INFO - "slavebuilddir": "test" 18:53:20 INFO - } 18:53:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:53:20 INFO - Running post-action listener: _resource_record_post_action 18:53:20 INFO - ##### 18:53:20 INFO - ##### Finished read-buildbot-config step (success) 18:53:20 INFO - ##### 18:53:20 INFO - ##### 18:53:20 INFO - ##### Running download-and-extract step. 18:53:20 INFO - ##### 18:53:20 INFO - Running pre-action listener: _resource_record_pre_action 18:53:20 INFO - Running main action method: download_and_extract 18:53:20 INFO - mkdir: /builds/slave/test/build/tests 18:53: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')]} 18:53:20 INFO - https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:53:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:53:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:53:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:53:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:53:50 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 18:53:50 INFO - retry: attempt #1 caught exception: timed out 18:53:50 INFO - retry: Failed, sleeping 30 seconds before retrying 18:54:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 #2 18:54:25 INFO - Downloaded 1199 bytes. 18:54:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:54:25 INFO - Using the following test package requirements: 18:54:25 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:54:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:54:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:54:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'jsshell-mac.zip'], 18:54:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:54:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:54:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:54:25 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:54:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:54:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:54:25 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:54:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:54:25 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')]} 18:54:25 INFO - https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:54:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:54:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:54:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:27 INFO - Downloaded 18687110 bytes. 18:54:27 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'] 18:54:27 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 18:54:30 INFO - Return code: 0 18:54:30 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 18:54:30 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')]} 18:54:30 INFO - https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:54:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:54:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:54:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:32 INFO - Downloaded 18687110 bytes. 18:54:32 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'] 18:54:32 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 18:54:36 INFO - Return code: 0 18:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:36 INFO - https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:54:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:54:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:54:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:37 INFO - Downloaded 10834055 bytes. 18:54:37 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'] 18:54:37 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 18:54:37 INFO - Return code: 0 18:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:37 INFO - https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:54:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:54:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:54:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:54:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/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 18:54:41 INFO - Downloaded 111008113 bytes. 18:54:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:54:41 INFO - mkdir: /builds/slave/test/properties 18:54:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:54:41 INFO - Writing to file /builds/slave/test/properties/build_url 18:54:41 INFO - Contents: 18:54:41 INFO - build_url:https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:54:42 INFO - Running post-action listener: _resource_record_post_action 18:54:42 INFO - Running post-action listener: set_extra_try_arguments 18:54:42 INFO - ##### 18:54:42 INFO - ##### Finished download-and-extract step (success) 18:54:42 INFO - ##### 18:54:42 INFO - ##### 18:54:42 INFO - ##### Running populate-webroot step. 18:54:42 INFO - ##### 18:54:42 INFO - Running pre-action listener: _resource_record_pre_action 18:54:42 INFO - Running main action method: populate_webroot 18:54:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:54:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:54:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:54:42 INFO - '--tppagecycles', 18:54:42 INFO - '7'], 18:54:42 INFO - 'tests': ['tsvgm']}}, 18:54:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:54:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:54:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:54:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:54:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:54:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:54:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['damp', 'tps']}, 18:54:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'tests': ['damp', 'tps']}, 18:54:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['dromaeo_dom']}, 18:54:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:54:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['a11yr', 18:54:42 INFO - 'ts_paint', 18:54:42 INFO - 'tpaint', 18:54:42 INFO - 'sessionrestore', 18:54:42 INFO - 'sessionrestore_no_auto_restore', 18:54:42 INFO - 'tabpaint']}, 18:54:42 INFO - 'other-e10s': {'tests': ['a11yr', 18:54:42 INFO - 'ts_paint', 18:54:42 INFO - 'tpaint', 18:54:42 INFO - 'sessionrestore', 18:54:42 INFO - 'sessionrestore_no_auto_restore', 18:54:42 INFO - 'tabpaint']}, 18:54:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:54:42 INFO - 'ts_paint', 18:54:42 INFO - 'tpaint', 18:54:42 INFO - 'sessionrestore', 18:54:42 INFO - 'sessionrestore_no_auto_restore', 18:54:42 INFO - 'tabpaint']}, 18:54:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:54:42 INFO - 'ts_paint', 18:54:42 INFO - 'tpaint', 18:54:42 INFO - 'sessionrestore', 18:54:42 INFO - 'sessionrestore_no_auto_restore', 18:54:42 INFO - 'tabpaint']}, 18:54:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['a11yr', 18:54:42 INFO - 'ts_paint', 18:54:42 INFO - 'tpaint', 18:54:42 INFO - 'sessionrestore', 18:54:42 INFO - 'sessionrestore_no_auto_restore', 18:54:42 INFO - 'tabpaint']}, 18:54:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['a11yr', 18:54:42 INFO - 'ts_paint', 18:54:42 INFO - 'tpaint', 18:54:42 INFO - 'sessionrestore', 18:54:42 INFO - 'sessionrestore_no_auto_restore', 18:54:42 INFO - 'tabpaint']}, 18:54:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['tsvgx', 18:54:42 INFO - 'tsvgr_opacity', 18:54:42 INFO - 'tart', 18:54:42 INFO - 'tscrollx', 18:54:42 INFO - 'cart']}, 18:54:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:54:42 INFO - 'tsvgr_opacity', 18:54:42 INFO - 'tart', 18:54:42 INFO - 'tscrollx', 18:54:42 INFO - 'cart']}, 18:54:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'talos_options': ['--disable-e10s'], 18:54:42 INFO - 'tests': ['tp5o']}, 18:54:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'tests': ['tp5o']}, 18:54:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'talos_options': ['--disable-e10s', 18:54:42 INFO - '--xperf_path', 18:54:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:54:42 INFO - 'tests': ['tp5n']}, 18:54:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:42 INFO - 'talos_options': ['--xperf_path', 18:54:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:54:42 INFO - 'tests': ['tp5n']}}, 18:54:42 INFO - 'talos.zip': {'path': '', 18:54:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:54:42 INFO - Running post-action listener: _resource_record_post_action 18:54:42 INFO - ##### 18:54:42 INFO - ##### Finished populate-webroot step (success) 18:54:42 INFO - ##### 18:54:42 INFO - ##### 18:54:42 INFO - ##### Running create-virtualenv step. 18:54:42 INFO - ##### 18:54:42 INFO - Running pre-action listener: _resource_record_pre_action 18:54:42 INFO - Running main action method: create_virtualenv 18:54:42 INFO - Creating virtualenv /builds/slave/test/build/venv 18:54:42 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 18:54:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:54:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:54:42 INFO - Using real prefix '/tools/python27' 18:54:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:54:43 INFO - Installing distribute.............................................................................................................................................................................................done. 18:54:46 INFO - Installing pip.................done. 18:54:46 INFO - Return code: 0 18:54:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:54: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')]} 18:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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')]} 18:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:46 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:54:46 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 18:54:46 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 18:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:46 INFO - 'HOME': '/Users/cltbld', 18:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:46 INFO - 'LOGNAME': 'cltbld', 18:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:46 INFO - 'MOZ_NO_REMOTE': '1', 18:54:46 INFO - 'NO_EM_RESTART': '1', 18:54:46 INFO - 'PAGER': '/bin/cat', 18:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:46 INFO - 'PWD': '/builds/slave/test', 18:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:46 INFO - 'SHELL': '/bin/bash', 18:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:54:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:46 INFO - 'USER': 'cltbld', 18:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:46 INFO - 'XPC_FLAGS': '0x0', 18:54:46 INFO - 'XPC_SERVICE_NAME': '0', 18:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:46 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 18:54:46 INFO - Cleaning up... 18:54:46 INFO - Return code: 0 18:54:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:54: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')]} 18:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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')]} 18:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:46 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:54:46 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 18:54:46 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 18:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:46 INFO - 'HOME': '/Users/cltbld', 18:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:46 INFO - 'LOGNAME': 'cltbld', 18:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:46 INFO - 'MOZ_NO_REMOTE': '1', 18:54:46 INFO - 'NO_EM_RESTART': '1', 18:54:46 INFO - 'PAGER': '/bin/cat', 18:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:46 INFO - 'PWD': '/builds/slave/test', 18:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:46 INFO - 'SHELL': '/bin/bash', 18:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:54:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:46 INFO - 'USER': 'cltbld', 18:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:46 INFO - 'XPC_FLAGS': '0x0', 18:54:46 INFO - 'XPC_SERVICE_NAME': '0', 18:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:46 INFO - Downloading/unpacking psutil>=3.1.1 18:54:46 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 18:54:46 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 18:54:46 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 18:54:46 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 18:54:46 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 18:54:46 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 18:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:54:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:54:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:54:49 INFO - Installing collected packages: psutil 18:54:49 INFO - Running setup.py install for psutil 18:54:49 INFO - building 'psutil._psutil_osx' extension 18:54:49 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 18:54:50 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 18:54:50 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 18:54:50 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 18:54:50 INFO - building 'psutil._psutil_posix' extension 18:54:50 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 18:54:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:54:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:54:50 INFO - ^ 18:54:50 INFO - 1 warning generated. 18:54:50 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 18:54:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:54:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:54:51 INFO - Successfully installed psutil 18:54:51 INFO - Cleaning up... 18:54:51 INFO - Return code: 0 18:54:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:54:51 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')]} 18:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:51 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')]} 18:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:51 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:54:51 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 18:54:51 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 18:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:51 INFO - 'HOME': '/Users/cltbld', 18:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:51 INFO - 'LOGNAME': 'cltbld', 18:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:51 INFO - 'MOZ_NO_REMOTE': '1', 18:54:51 INFO - 'NO_EM_RESTART': '1', 18:54:51 INFO - 'PAGER': '/bin/cat', 18:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:51 INFO - 'PWD': '/builds/slave/test', 18:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:51 INFO - 'SHELL': '/bin/bash', 18:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:54:51 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:51 INFO - 'USER': 'cltbld', 18:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:51 INFO - 'XPC_FLAGS': '0x0', 18:54:51 INFO - 'XPC_SERVICE_NAME': '0', 18:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:51 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 18:54:51 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 18:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:54:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:54:53 INFO - Installing collected packages: mozsystemmonitor 18:54:53 INFO - Running setup.py install for mozsystemmonitor 18:54:53 INFO - Successfully installed mozsystemmonitor 18:54:53 INFO - Cleaning up... 18:54:54 INFO - Return code: 0 18:54:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:54:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:54:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:54:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:54:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:54 INFO - 'HOME': '/Users/cltbld', 18:54:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:54 INFO - 'LOGNAME': 'cltbld', 18:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:54 INFO - 'MOZ_NO_REMOTE': '1', 18:54:54 INFO - 'NO_EM_RESTART': '1', 18:54:54 INFO - 'PAGER': '/bin/cat', 18:54:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:54 INFO - 'PWD': '/builds/slave/test', 18:54:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:54 INFO - 'SHELL': '/bin/bash', 18:54:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:54:54 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:54:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:54 INFO - 'USER': 'cltbld', 18:54:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:54 INFO - 'XPC_FLAGS': '0x0', 18:54:54 INFO - 'XPC_SERVICE_NAME': '0', 18:54:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:54:54 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 18:54:54 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 18:54:54 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 18:54:54 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 18:54:54 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 18:54:54 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 18:54:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:54:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:54:56 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 18:54:56 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 18:54:56 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 18:54:56 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 18:54:56 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 18:54:56 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 18:54:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:54:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 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 18:54:57 INFO - Downloading docopt-0.6.1.tar.gz 18:54:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:54:58 INFO - Installing collected packages: blobuploader, requests, docopt 18:54:58 INFO - Running setup.py install for blobuploader 18:54:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:54:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:54:58 INFO - Running setup.py install for requests 18:54:58 INFO - Running setup.py install for docopt 18:54:58 INFO - Successfully installed blobuploader requests docopt 18:54:58 INFO - Cleaning up... 18:54:58 INFO - Return code: 0 18:54:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:54:58 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')]} 18:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:58 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')]} 18:54:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:58 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:54:58 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 18:54:58 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 18:54:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:54:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:58 INFO - 'HOME': '/Users/cltbld', 18:54:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:58 INFO - 'LOGNAME': 'cltbld', 18:54:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:58 INFO - 'MOZ_NO_REMOTE': '1', 18:54:58 INFO - 'NO_EM_RESTART': '1', 18:54:58 INFO - 'PAGER': '/bin/cat', 18:54:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:58 INFO - 'PWD': '/builds/slave/test', 18:54:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:58 INFO - 'SHELL': '/bin/bash', 18:54:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:54:58 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:54:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:58 INFO - 'USER': 'cltbld', 18:54:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:58 INFO - 'XPC_FLAGS': '0x0', 18:54:58 INFO - 'XPC_SERVICE_NAME': '0', 18:54:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-8peYiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ZQRneC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-djaXJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-9CSfTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-kUOpzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-w_siWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ctzXnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6GIeDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:54:59 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yEFKmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-q9Y9Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-kwYUZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-2I0QTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-4oUUlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-mySx4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_q4fzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-R19NyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:55:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-lSx1UX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:55:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:55:00 INFO - Running setup.py install for manifestparser 18:55:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:55:01 INFO - Running setup.py install for mozcrash 18:55:01 INFO - Running setup.py install for mozdebug 18:55:01 INFO - Running setup.py install for mozdevice 18:55:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:55:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:55:01 INFO - Running setup.py install for mozfile 18:55:01 INFO - Running setup.py install for mozhttpd 18:55:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:55:01 INFO - Running setup.py install for mozinfo 18:55:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:55:01 INFO - Running setup.py install for mozInstall 18:55:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Running setup.py install for mozleak 18:55:02 INFO - Running setup.py install for mozlog 18:55:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Running setup.py install for moznetwork 18:55:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Running setup.py install for mozprocess 18:55:02 INFO - Running setup.py install for mozprofile 18:55:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:55:02 INFO - Running setup.py install for mozrunner 18:55:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:55:03 INFO - Running setup.py install for mozscreenshot 18:55:03 INFO - Running setup.py install for moztest 18:55:03 INFO - Running setup.py install for mozversion 18:55:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:55:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:55:03 INFO - Cleaning up... 18:55:03 INFO - Return code: 0 18:55:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:55: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')]} 18:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55: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')]} 18:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:03 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:55:03 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 18:55:03 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 18:55:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:55:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:03 INFO - 'HOME': '/Users/cltbld', 18:55:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:03 INFO - 'LOGNAME': 'cltbld', 18:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:03 INFO - 'MOZ_NO_REMOTE': '1', 18:55:03 INFO - 'NO_EM_RESTART': '1', 18:55:03 INFO - 'PAGER': '/bin/cat', 18:55:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:03 INFO - 'PWD': '/builds/slave/test', 18:55:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:03 INFO - 'SHELL': '/bin/bash', 18:55:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:55:03 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:55:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:03 INFO - 'USER': 'cltbld', 18:55:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:03 INFO - 'XPC_FLAGS': '0x0', 18:55:03 INFO - 'XPC_SERVICE_NAME': '0', 18:55:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:55:03 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OBPzHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:55:03 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)) 18:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:55:03 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Oan2mU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:55:03 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)) 18:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:55:03 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-GfUbZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Tf0g7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-nVagNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Onz3lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-1uiaVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-EyyhXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Hrn7bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-3yaZq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-PTDmX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:55:04 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)) 18:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:55:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-VcJ_8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:55:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-kEHzkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:55:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-gsRthj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-HuMcWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:55:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-WBewuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:55:05 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)) 18:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:55:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Lq0g0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 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)) 18:55:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:07 INFO - Downloading blessings-1.6.tar.gz 18:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:55:08 INFO - Installing collected packages: blessings 18:55:08 INFO - Running setup.py install for blessings 18:55:08 INFO - Successfully installed blessings 18:55:08 INFO - Cleaning up... 18:55:08 INFO - Return code: 0 18:55:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:55:08 INFO - Reading from file tmpfile_stdout 18:55:08 INFO - Current package versions: 18:55:08 INFO - blessings == 1.6 18:55:08 INFO - blobuploader == 1.2.4 18:55:08 INFO - docopt == 0.6.1 18:55:08 INFO - manifestparser == 1.1 18:55:08 INFO - mozInstall == 1.12 18:55:08 INFO - mozcrash == 0.17 18:55:08 INFO - mozdebug == 0.1 18:55:08 INFO - mozdevice == 0.48 18:55:08 INFO - mozfile == 1.2 18:55:08 INFO - mozhttpd == 0.7 18:55:08 INFO - mozinfo == 0.9 18:55:08 INFO - mozleak == 0.1 18:55:08 INFO - mozlog == 3.2 18:55:08 INFO - moznetwork == 0.27 18:55:08 INFO - mozprocess == 0.22 18:55:08 INFO - mozprofile == 0.28 18:55:08 INFO - mozrunner == 6.11 18:55:08 INFO - mozscreenshot == 0.1 18:55:08 INFO - mozsystemmonitor == 0.3 18:55:08 INFO - moztest == 0.7 18:55:08 INFO - mozversion == 1.4 18:55:08 INFO - psutil == 3.1.1 18:55:08 INFO - requests == 1.2.3 18:55:08 INFO - wsgiref == 0.1.2 18:55:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:55:08 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')]} 18:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:08 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')]} 18:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:08 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:55:08 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 18:55:08 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 18:55:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:55:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:08 INFO - 'HOME': '/Users/cltbld', 18:55:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:08 INFO - 'LOGNAME': 'cltbld', 18:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:08 INFO - 'MOZ_NO_REMOTE': '1', 18:55:08 INFO - 'NO_EM_RESTART': '1', 18:55:08 INFO - 'PAGER': '/bin/cat', 18:55:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:08 INFO - 'PWD': '/builds/slave/test', 18:55:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:08 INFO - 'SHELL': '/bin/bash', 18:55:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:55:08 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:55:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:08 INFO - 'USER': 'cltbld', 18:55:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:08 INFO - 'XPC_FLAGS': '0x0', 18:55:08 INFO - 'XPC_SERVICE_NAME': '0', 18:55:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 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)) 18:55:08 INFO - Cleaning up... 18:55:08 INFO - Return code: 0 18:55:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:55:08 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')]} 18:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:08 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')]} 18:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:08 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:55:08 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 18:55:08 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 18:55:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:55:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:08 INFO - 'HOME': '/Users/cltbld', 18:55:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:08 INFO - 'LOGNAME': 'cltbld', 18:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:08 INFO - 'MOZ_NO_REMOTE': '1', 18:55:08 INFO - 'NO_EM_RESTART': '1', 18:55:08 INFO - 'PAGER': '/bin/cat', 18:55:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:08 INFO - 'PWD': '/builds/slave/test', 18:55:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:08 INFO - 'SHELL': '/bin/bash', 18:55:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:55:08 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:55:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:08 INFO - 'USER': 'cltbld', 18:55:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:08 INFO - 'XPC_FLAGS': '0x0', 18:55:08 INFO - 'XPC_SERVICE_NAME': '0', 18:55:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:09 INFO - Downloading/unpacking jsonschema 18:55: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 18:55: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 18:55: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 18:55: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 18:55: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 18:55: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 18:55:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:55:11 INFO - Downloading/unpacking functools32 (from jsonschema) 18:55:11 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 18:55:11 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 18:55:11 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 18:55:11 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 18:55:11 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 18:55:11 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 18:55:11 INFO - Downloading functools32-3.2.3-2.tar.gz 18:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:55:12 INFO - warning: no files found matching '*.txt' 18:55:12 INFO - no previously-included directories found matching 'build' 18:55:12 INFO - no previously-included directories found matching 'dist' 18:55:12 INFO - no previously-included directories found matching '.git*' 18:55:12 INFO - Installing collected packages: jsonschema, functools32 18:55:12 INFO - Running setup.py install for functools32 18:55:12 INFO - warning: no files found matching '*.txt' 18:55:12 INFO - no previously-included directories found matching 'build' 18:55:12 INFO - no previously-included directories found matching 'dist' 18:55:12 INFO - no previously-included directories found matching '.git*' 18:55:12 INFO - Successfully installed jsonschema functools32 18:55:12 INFO - Cleaning up... 18:55:12 INFO - Return code: 0 18:55:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:55:12 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')]} 18:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:12 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')]} 18:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:12 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 0x10ce0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f980a4c0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, '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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 18:55:12 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 18:55:12 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 18:55:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:55:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:12 INFO - 'HOME': '/Users/cltbld', 18:55:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:12 INFO - 'LOGNAME': 'cltbld', 18:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:12 INFO - 'MOZ_NO_REMOTE': '1', 18:55:12 INFO - 'NO_EM_RESTART': '1', 18:55:12 INFO - 'PAGER': '/bin/cat', 18:55:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:12 INFO - 'PWD': '/builds/slave/test', 18:55:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:12 INFO - 'SHELL': '/bin/bash', 18:55:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:55:12 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:55:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:12 INFO - 'USER': 'cltbld', 18:55:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:12 INFO - 'XPC_FLAGS': '0x0', 18:55:12 INFO - 'XPC_SERVICE_NAME': '0', 18:55:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:12 INFO - Downloading/unpacking flake8 18:55:12 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 18:55:12 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 18:55:12 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 18:55:12 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 18:55:12 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 18:55:12 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 18:55:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:55:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:55:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:55:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:55:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:55:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:55:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:55:16 INFO - no previously-included directories found matching 'docs/_build' 18:55:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:55:16 INFO - Running setup.py install for pep8 18:55:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:55:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:55:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:55:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:55:16 INFO - no previously-included directories found matching 'docs/_build' 18:55:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:55:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:55:16 INFO - Cleaning up... 18:55:16 INFO - Return code: 0 18:55:16 INFO - Running post-action listener: _resource_record_post_action 18:55:16 INFO - Running post-action listener: _start_resource_monitoring 18:55:16 INFO - Starting resource monitoring. 18:55:16 INFO - ##### 18:55:16 INFO - ##### Finished create-virtualenv step (success) 18:55:16 INFO - ##### 18:55:16 INFO - ##### 18:55:16 INFO - ##### Running install step. 18:55:16 INFO - ##### 18:55:16 INFO - Running pre-action listener: _resource_record_pre_action 18:55:16 INFO - Running main action method: install 18:55:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:55:17 INFO - Reading from file tmpfile_stdout 18:55:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:55:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:55:17 INFO - Reading from file tmpfile_stdout 18:55:17 INFO - Output received: 18:55:17 INFO - Usage: mozinstall [options] installer 18:55:17 INFO - Options: 18:55:17 INFO - -h, --help show this help message and exit 18:55:17 INFO - -d DEST, --destination=DEST 18:55:17 INFO - Directory to install application into. [default: 18:55:17 INFO - "/builds/slave/test"] 18:55:17 INFO - --app=APP Application being installed. [default: firefox] 18:55:17 INFO - mkdir: /builds/slave/test/build/application 18:55:17 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'] 18:55:17 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 18:55:50 INFO - Reading from file tmpfile_stdout 18:55:50 INFO - Output received: 18:55:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:55:50 INFO - Running post-action listener: _resource_record_post_action 18:55:50 INFO - ##### 18:55:50 INFO - ##### Finished install step (success) 18:55:50 INFO - ##### 18:55:50 INFO - ##### 18:55:50 INFO - ##### Running run-tests step. 18:55:50 INFO - ##### 18:55:50 INFO - Running pre-action listener: _resource_record_pre_action 18:55:50 INFO - Running main action method: run_tests 18:55:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:55:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:55:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:55:50 INFO - 2016-05-31 18:55:50.774 screenresolution[1652:7932] starting screenresolution argv=screenresolution get 18:55:50 INFO - 2016-05-31 18:55:50.776 screenresolution[1652:7932] Display 0: 1600x1200x32@60 18:55:50 INFO - 2016-05-31 18:55:50.782 screenresolution[1653:7934] starting screenresolution argv=screenresolution list 18:55:50 INFO - Available Modes on Display 0 18:55:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:55:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:55:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:55:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:55:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:55:51 INFO - Intel Iris: 18:55:51 INFO - Chipset Model: Intel Iris 18:55:51 INFO - Type: GPU 18:55:51 INFO - Bus: Built-In 18:55:51 INFO - VRAM (Dynamic, Max): 1536 MB 18:55:51 INFO - Vendor: Intel (0x8086) 18:55:51 INFO - Device ID: 0x0a2e 18:55:51 INFO - Revision ID: 0x0009 18:55:51 INFO - Displays: 18:55:51 INFO - Display: 18:55:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:55:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:55:51 INFO - Display Serial Number: 1600x1200 60 18:55:51 INFO - Main Display: Yes 18:55:51 INFO - Mirror: Off 18:55:51 INFO - Online: Yes 18:55:51 INFO - Rotation: Supported 18:55:51 INFO - Return code: 0 18:55:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:55:51 INFO - Python 2.7.3 18:55:51 INFO - Return code: 0 18:55:51 INFO - grabbing minidump binary from tooltool 18:55:51 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')]} 18:55:51 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 0x10ccb2d30>, '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 0x10cad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:55:51 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 18:55:51 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 18:55:51 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 18:55:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:55:51 INFO - Return code: 0 18:55:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:55:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:55:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:55:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:55:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:55:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:55:52 INFO - Return code: 0 18:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SmAJtC3iNA/Render', 18:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:52 INFO - 'HOME': '/Users/cltbld', 18:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:52 INFO - 'LOGNAME': 'cltbld', 18:55:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:55:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:52 INFO - 'MOZ_NO_REMOTE': '1', 18:55:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:55:52 INFO - 'NO_EM_RESTART': '1', 18:55:52 INFO - 'PAGER': '/bin/cat', 18:55:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:52 INFO - 'PWD': '/builds/slave/test', 18:55:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:52 INFO - 'SHELL': '/bin/bash', 18:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JqkJD5ik0s/Listeners', 18:55:52 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 18:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:52 INFO - 'USER': 'cltbld', 18:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:52 INFO - 'XPC_FLAGS': '0x0', 18:55:52 INFO - 'XPC_SERVICE_NAME': '0', 18:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:55:52 INFO - mozversion application_buildid: 20160531170040 18:55:52 INFO - mozversion application_changeset: 25321494921c824703a605127fb1f99b1faf5910 18:55:52 INFO - mozversion application_display_name: Nightly 18:55:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:55:52 INFO - mozversion application_name: Firefox 18:55:52 INFO - mozversion application_remotingname: firefox 18:55:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:55:52 INFO - mozversion application_vendor: Mozilla 18:55:52 INFO - mozversion application_version: 49.0a1 18:55:52 INFO - mozversion platform_buildid: 20160531170040 18:55:52 INFO - mozversion platform_changeset: 25321494921c824703a605127fb1f99b1faf5910 18:55:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:55:52 INFO - mozversion platform_version: 49.0a1 18:55:52 INFO - using testdate: 1464746152 18:55:52 INFO - actual date: 1464746152 18:55:52 INFO - starting webserver on 'localhost:49271' 18:55:52 INFO - SUITE-START | Running 2 tests 18:55:52 INFO - TEST-START | dromaeo_css 18:55:52 INFO - operating with platform_type : mac_ 18:55:52 INFO - Initialising browser for dromaeo_css test... 18:55:52 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmp4aEG40/profile http://localhost:49271/getInfo.html) 18:55:54 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 18:55:54 INFO - PROCESS | 1671 | colorDepth:24 18:55:54 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 18:55:54 INFO - PROCESS | 1671 | __metrics 18:55:54 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:55:54 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:55:55 INFO - TEST-INFO | 1671: exit 0 18:55:55 INFO - Browser initialized. 18:55:55 INFO - Running cycle 1/1 for dromaeo_css test... 18:55:55 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmp4aEG40/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:56:07 INFO - PROCESS | 1674 | RSS: Main: 241762304 18:56:07 INFO - PROCESS | 1674 | 18:56:59 INFO - PROCESS | 1674 | 1464746219025 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] 18:59:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 18:59:11 INFO - PROCESS | 1674 | RSS: Main: 334389248 18:59:11 INFO - PROCESS | 1674 | 19:02:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 19:02:15 INFO - PROCESS | 1674 | RSS: Main: 376344576 19:02:15 INFO - PROCESS | 1674 | 19:05:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 19:05:18 INFO - PROCESS | 1674 | RSS: Main: 373178368 19:05:18 INFO - PROCESS | 1674 | 19:08:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 19:08:20 INFO - PROCESS | 1674 | RSS: Main: 369872896 19:08:20 INFO - PROCESS | 1674 | 19:11:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 19:11:24 INFO - PROCESS | 1674 | RSS: Main: 335376384 19:11:24 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 19:14:27 INFO - PROCESS | 1674 | RSS: Main: 381116416 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | __start_tp_report 19:14:27 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 19:14:27 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 19:14:27 INFO - PROCESS | 1674 | |i|pagename|runs| 19:14:27 INFO - PROCESS | 1674 | |0;dojo.html;2965.703429657033;3278.786878852876;3371.6122645895716;3375.2236985493337;3444.2422667013266;5462.808801691942;5496.230527726117;5554.833354999351;5570.916436253455;5600.747966341514;5540;5581.748821303041;5597.608167428281;5600.971995140028;5606.747696353664;5030.320906677597;5076.46697096805;5095.439501654815;5157.948420515784;5162.8709282268055;5084.644074914758;5173.249878767577;5184.481551844804;5187.455317191697;5195.818146364878;5028.346314979048;5046.82336118236;5065.822696205633;5073.822416215433;5166.741662916858;5125.589952803785;5127.7948882044675;5139;5150.330457040598;5196.454372290893;4963.032208719301;5013.724245166514;5013.974930125362;5024.723640199788;5035.244713292998;5364.624476286662;5370.167624018283;5411.756470958816;5462.399136095027;5473.124300111963;6070.660461235068;6086.482648974842;6092.1775560299675;6092.3564182880355;6119.296280927706;5114.693118412907;5963.493103086243;6078.544109191784;6144.324124346318;6183.6598987055695;5553.389127196006;5590.552755779528;5614.803481878155;5633.746481408264;5639.351474580394;6197.876042479215;6234.594751341148;6236.532260080512;6248.656323902138;6268.655223962726;5697.800576979786;5816.534677225812;5867.237259156368;5871.911921321183;5948.256467941507;6111.96944015277;6115.312191768499;6132.846678833065;6133.110698948717;6134.202553667995;6022.518198544106;6024.8795024100145;6031.125486804389;6037.969810151009;6045.637261764308;5878.030125029408;5940.851478713067;5955.702214889238;6000.519958403404;6025.969870150621;4822.132016237111;4828.203346447867;4879.512048795092;4880.926786098212;4884.169691152512;4330.112326972963;4376.256036473807;4405.207062728739;4418.867433976986;4430.534793846664;5608.439156084441;5609.298837645294;5649.915251271234;5656.85857853557;5667.376588575254;6159.168512250879;6165.876682466415;6180.980138277134;6187.412195841388;6188.81433556994;5357.919631205535;5415.48552887475;5458.263134476795;5576.776928922879;5604.41153678866;4441.777911104432;5216.452272511332;5281.841544753664;5309.7079660618265;5314.123169677037;6313.715882785285;6347.143135676624;6381.1066450696935;6389.93610063912;6392.7123279452535;6156.846078848064;6159.846003849761;6166.969165154325;6168.136460895568;6168.691565421801;6222.253329600296;6240.594361366497;6295.150154729053;6321.841953951188;6327.525435592441;5999.98000339952;6003.729832157651;6005.699715014319;6017.759289628366;6053.878922421615;5799.043157879071;5978.103284507359;6118.388161183846;6127.417895299939;6139.017757159012;4567.38340324052;5831.329397119385;5851.093080572518;5915.201447804491;5920.674362910081;5885.499732522612;5917.437843404869;5927.614705044159;5931.110333449845;5936.970315148396;5458.91811622818;5770.13447982806;5788.421157884178;5810.477057064884;5839.35767065614;5752.223449834219;5837.970810145922;5855.824325270249;5873.177755114373;5908.172855800278;5787.36339002701;5791.420857914175;5797.681127538026;5803.7968671096305;5813 19:14:27 INFO - PROCESS | 1674 | |1;ext.html;501.5511117549793;505.25979440120216;509.2666560153379;512.5310341037952;518.6991544903956;25168.622470662933;31140.22149446265;32854.67145328549;32868;32883.177920552;33115.33769324618;33224.66775332246;34065.31869362611;43666.56333436665;45087.77456112723;9584.712458626253;9762.90237097627;9776.560054797552;9792.314537982344;9803.656872009482;9487.383320084207;9637.22902167825;9638.132568068873;9645.71062868115;9654.227661787076;7412.962935185316;8780.780480488007;11472;11683.941580292087;11752.882471175264;11756.647300580953;11868.109891758153;11881.287122772661;11890.405479726065;11892.04863610909;8565.614547345385;11568.479418426192;11617.4191290436;11740.412979350998;11745.882541174564;41271;41784.79107604443;41806.581934180576;41906.58093419057;41947.7902610488;3445.758796884219;3463.705585025276;3507.9824600877087;3510.6313837047255;3838.309104361214;28043.71956280452;31672.733090676345;32461;32974.83512582446;32990.50514242288;2846.274200078987;2967.55486676998;3286.5234540991423;3296.917577060569;3386.7290616750847;512967;514414;514425;516223.41888290696;516463;64962.67518662376;65200;65298.69402612016;65331;65459;68523.3147668527;68554.65722671355;68575.65712171407;68583.31416685869;68766.49868010548;16492.17539123039;16623.50129496117;16652.250648720837;16696.74954875678;16711.99728016323;13120.60638180856;13194.934025329812;13201.943844492418;13261.005424593193;13317;15117.714994225615;15839.445619403496;16217.351305947866;16258.75611865823;18383.90808045951;5960.344362120163;7154.4634152438775;7256.8911466328045;7313.634318284171;7324.267573242633;889.1242126505387;939.8684184214216;1080.9675709728722;1083.4691001409253;1090.0516550601028;30828.691713082582;30884.382312353628;31173.688263117532;31417.900373486817;31984.360312793615;1082.8917108289265;1164.8194529847888;1168.8889555492215;1204.740980689156;1241.3979220078254;15724.29240684172;15824.76262856058;15827.050376977419;16175;16267.267972941247;552950;629667;631028;631538;631681.0495685136;52808;60894.69552652208;61386.38613613896;61390.38609613936;61547.38452615506;1421.232534431416;1437.0730878583256;1447.5669087602987;1452.0489079652843;1452.7167202395642;31835.522467162547;31938.36123277568;32161.26629236541;32729.50905736368;33018.504722428705;53384.19923701225;53845;53916.460835391146;54026.64933376697;54040.64898377572;406804;413500;415290;594961;821423;40325;41010.589894100674;41436.58563414448;41496.792516037225;41658.375124373764;48278.5172148274;48552.27171592358;48623.5137648619;49457.752711236215;49521.50478495169;37923.81038094792;38478;38562;39635.80182099187;40544.98637534085;38288.80855595704;38740;38807.41788873113;39636.80181599189;40198.397024044076;38478.42282365711;38513.614863851006;40110.598894011855;40123.79938100291;41797.58202417937;33386.165345866546;36183.27633447263;36232.81883590565;36390.81804591066;36557;20561.58876822485;23081.192158274378;23111.88444057769;24574.385640358418;25453.236402907944 19:14:27 INFO - PROCESS | 1674 | |2;jquery.html;380.40466669661976;385.18194123735975;386.6558762701196;398.5436675007119;403.43720509888703;19390.806091939077;21246.57506849862;21376.679349809765;21626.89186554065;21626.89186554067;20821.687674684854;20864.895675521602;21646.134154633797;21831.235906743266;21864.89067554664;5690.317161940573;5798.246227990356;5975.193348897887;6005.069214271794;6019.3980601939675;4172.436721042651;4389.824407023715;5897.616654917417;6306.905396419057;6653.866922661544;6519.869602607945;6633;6638.701258443381;6639.170103737033;6649;6425.1968503937005;6595.340465953414;6625.701843417034;6646.833829154261;6651.235107962573;3642.508261384732;3827.9617203827884;5335.893282134374;5652.34997975234;5679.744411501492;25120.99516019357;25809.870950645316;26004.34989125268;26290.868545657147;26415.20754377372;2236.1837929155904;2264.3320220534815;2417.721961974378;2458.3485376375274;2560.8591527465983;14996.775048374284;15340.693186136326;15427.228638568138;15939;15991.680166396607;1820.25369598464;1821.330466977198;1873.2226126157627;1879.6334714730633;1930.7683078030727;78077.60961195214;117080.41459792732;118465.40767296194;119146;122860.38569807183;8913.5097569633;10412.635557755442;10593.89406105929;10774.407407592658;10836;13368.131071480517;13437.798433023512;13458.394372253271;13460.394282257323;13462.528811491551;8344.207300306583;8532.146785321542;8556;8654.134586541422;8672.913270867211;8595.796588477619;8602.698905538276;8647.870281945776;8741.388102832869;8767.25478334336;5799.782045770351;6193.893638502199;6585.473162147016;6588.439982601437;6671.733130674816;1678.202853644509;1751.59713265947;1803.5310819186843;1865.869389142774;1869.4298239036953;821.5727821532889;929.3215952354819;967.7338731848798;983.2036050798876;1030.9742256443649;18031.819681803277;20788.064537095866;21507.13971441125;21714.82851714816;22550.774492255197;938.8169306985072;949.7483166960761;970.3304719743354;978.7357413498459;995.5022488755623;9357.719268421959;11170.101292350648;11227.438628068563;11253;11380.544778208945;579280;592077;598184;604520;606810;104525;143043.85434218496;144187;144419;146488;1030.9845352319721;1036.124474818777;1058.222206678091;1058.7564860082225;1058.957641694339;18180.545486362418;18420.6842768303;18424.631507370046;18430.89414635126;18850.24599016024;51437.7428112857;52998.470015300896;53473;55742.72128639331;56881.99999999834;76526.6173669128;77188.4562308762;77358.6132069336;77995.83006255025;78803.21196787959;6998.370146686822;7095.645217739196;8196;8230.21812927771;8286.295664868554;10166.745831354276;12725.363731813557;12792.040596955452;12924.483020679067;13073.215607063226;10976.560937562408;11011.779764404828;11054;11061.225714199929;11362.772744545227;10694.037536621741;10791.6222932197;11002.669919902415;11014.889851101387;11047.779044419227;11271.323720576791;11293.887061129284;11444.484998174936;11535.826962595736;11755.88244117548;7119.576084782961;7246.130464344313;7478.738244161428;7549.980752598329;8441.32469402434;10047.246456515937;11459.885401145882;11600.825987610024;11609.30344179352;11683.415829208336 19:14:27 INFO - PROCESS | 1674 | |3;mootools.html;1381.502659042745;1556.1674504140283;1562.9765553516695;1825.2151574822822;1965.4103768869345;8007.159248278934;8647.870281945769;8907.287417006624;8926.973398059225;9034.638614455414;8223.753287401387;8830.984436789755;8857.379983401164;8873.866891996626;8881.422707524007;2357.2810292860713;2386.868722220277;2391.5097405032016;2391.796697280733;2393.74865639108;2343.027643527934;2371.134535894395;2388.3312672451743;2391.7369089400154;2393.437542177596;2350.1069593554425;2361.633946738258;2370.9288721338303;2382.9880850595723;2384.260879127476;2326.9883650581637;2358.596635002171;2364.5507353602848;2365.822563307742;2372.6203807390734;1950.8439324854119;2258.5482903419215;2361.5867223235864;2361.6693662887037;2362.4566349739484;7346.191918888918;7594.240575942362;7962.800929976739;8808.471491710518;8900.976387715431;5164.561012313958;5399.2980912481125;5409.404965453797;5431.131019036935;5434.130539113723;3578.1949061461223;3602.0814692253307;3710.888673339804;3711.851525938959;3718.219173973469;1074.790415868906;1093.4532733633184;1096.1450068946156;1103.3324838472688;1104.3539529375325;11551.364674942875;11776.411179440993;11782.941085294518;11790.174687771774;11792.29246245228;4693.108309421199;4704.623630109583;4823.975880120647;4828.420589529279;4834.129856625771;10222.846657300146;10238.692839214835;10249.231307651957;10294.073533382032;10294.433806140734;5408;5420.105682562412;5422.132458806651;5429.158480435539;5429.728513574306;4062.7968601569805;4738.786754596052;4940.92588611164;4941.901161976813;4946.604271658259;3262.4127657021963;3796.1079146401125;3866.1881004988704;3885.7862817545306;3940.625640564142;991.177322822056;1178.452019810775;1181.4329122021477;1191.255465334166;1192.8866757658009;839.8992120945403;853.8463076646264;877.9692710755093;926.9258459323238;959.1463597398321;5832.62087964281;5878.617889837147;5949.940500595025;6066.48434883031;6177.938220617736;432.91125319309464;434.45258973692745;452.3757215043219;452.590405682851;453.4332084893883;4079.2453396121814;4087.6729861611;4137.97931010349;4145.150244199932;4147.6267135957905;10492.108170805413;10775.245732798785;10817.729556761144;10870.456477176109;10907.945460272647;10848.02367786903;10861.891381086247;10870.402127883042;10871.402072886067;10878.02097811185;7774.144844067261;8230.83538329242;8267.875981860278;8307.252347288773;8326.5004099753;5951.137085122719;6084.602402363732;6104.9389506106145;6270.7805226816845;6324.177856878577;8669.653213871627;8983.595738191667;9796.804063918538;9816.85274720894;10017.39895606266;10257.897421025695;10324.896751032695;10379.896201038195;10398.376097434179;10407.271490995558;6026;7443.962780186064;7446.962765186139;7471.476996610185;7521.887171692539;8085.757427277191;8116.513009219466;8191.017077950684;8199.44210599984;8212.13772553897;7261.818454538678;7411.370033547209;7444.776656700307;7447.031885854804;7601.163871974189;7168.641567921687;7253.673584688807;7259.709611615476;7352.926470735224;7384.667689954073;6874.587524748531;6919.792406227822;6949.30506949301;6970.407515361251;6984.057152284377;4900.632452566;4950.653454258168;4990.251462280687;5018.322526459027;5108.897822043613;4507.75207363585;4615.79228934692;4812.639052071038;4891.828785992473;5070.087384270719 19:14:27 INFO - PROCESS | 1674 | |4;prototype.html;310.5791652311118;317.23229783922903;320.9566708494354;323.75879969422783;330.1103525997438;5056.039352523023;5132.255822905677;5204.635675502716;5357.73211339433;5480.205370221315;4282.693778397587;4717.811287548494;5396.622236443451;5418.106012507941;5460.34475862897;3152.9752830330176;4001.4397984282077;4021.517417909855;4025.2754504189234;4053.1893621275717;3655.4516822476576;3712.9257414851686;3791.9620803791886;3902.7121050053556;4042.959570404302;2997.9400411991746;3778.622137786227;3879.301725689375;3972.4507442097606;4008.879733607982;3896.337622604164;4018.1963607278517;4020.0954785173394;4069.674426045909;4109.589041095866;2829.7877659175438;3540.76984995977;3541.8937431877084;3567.9821600892087;3586.4082426399614;4596.747178905159;4611.800931757733;4801.663883528155;5088.898222035538;5163.819266325698;4444.555544445562;4624.791884365178;4641.883952901205;4656.650751193674;4666.906661866744;5311.681299122065;5511.669299841982;5515.227868098469;5552.278203833516;5667.943320566783;3462.04793681737;3866.980665096685;3895.7662540247675;3929.5873933236885;3938.6258305460933;4989.403390914908;5402.459754024605;5470.753816078286;5822.563307751935;5897.911531327033;3516.507688923553;3956.2680904032845;3996.3805610130476;4007.338789099766;4013.2174226026227;3217.501287300472;3705.444183372516;4036.354183330653;4088.179156382087;4089.9386509202386;4642;4648.953510464852;4654.650901182357;4656.161890859677;4737.881552961204;4679.532046795362;5124.846909445419;5408.783648654088;5627.971860140673;5689.374168841424;1367.692269239433;1374.4021350712471;1381.675306303018;1392.7214557088623;1396.7066915947562;785.9567723775248;817.2440492544373;849.562475325208;862.3877047296364;876.8816209811594;4200.3456992416795;4526.977365113154;4671;4906.55840974314;5022.096022715873;4987.177115775857;5232.790688372423;5286.497782710637;5801.7220270547;5862;4796.712197268175;4885;5169.353830771153;5214.243934629458;5221.086309895829;2699.824511406752;2796.734310240524;2802.2574017885277;2982.388610334876;3005.3388254583956;5075.796968121308;5178.016176926372;5817.95276850171;5861.531077513789;6001.489873360813;5425.941941321484;5795.238141270403;5917.224832550218;5963.731632076567;5991.58058935879;5213.869653258623;5436.966976274495;5500.779968801363;5984.832957573231;6008.819735407945;4973.676711013773;5402.324709411324;5419;5818.4763371296785;5847.707614619337;4688.9296660549435;5074.238864170404;5769.913451298318;5790.801668790094;5794.016081718003;5142.614303927296;5671.731307477042;5934.368048786619;6081.969590152021;6103.23709536308;3011.4579375712583;3067.9386412271183;3278.885239016623;3313.4367157582788;3367.410703126992;4173;4366.956330436655;4828.599706085196;4978.8755281118265;4990.5508504234795;3994;4336.1978034063795;4419.602235798793;4493.528179541101;4765.547273009059;4452.287633978677;4468.597826195658;4544.954550454453;4759.906061060338;4769.642276829321;4367;4732;4739.4217365888535;5083.694978301313;5573.247611572386;5104.566111880383;5415.83752487426;5530.889382212414;5538.833834984957;5565.972170139124;4289.67827412939;5341.919871201857;5448;5465.6335916021;5647.905268680174 19:14:27 INFO - PROCESS | 1674 | |5;yui.html;685.6571714142929;718.253016862463;762.6720510180622;779.7504798464487;827.8675411934091;14586.635334116652;15268.08391496511;15276.618084547892;15279.618009549768;15282.923585382085;11921.58274460394;13828.377723002464;13870.791938120936;14811.629709257273;15360.539183824476;909.3498148823589;915.8809354783886;919.4896832258094;923.1737594852602;923.9769005774842;919.645936314517;923.4044041593169;923.6859467780953;924.2513564013171;927.8654595083709;941.3598752848061;945.4043952310035;946.9961840449112;949.4255975135047;952.1478276942132;942.1614762861059;943.4245110482634;943.9244860411151;945.1493655709847;948.9240860731124;700.6216643012767;704.232386698501;704.9118860142482;705.3158436316764;706.2231545300128;14772.48296309624;14786.704265914621;14808.111513309235;14812.851871481254;14849.925750371287;11395.917387848223;12405.069619778462;12947;13031.804522932163;13245;11990.640280791591;12235.510579576807;12244.877551224552;12349.753004939941;12479.875201247962;4191.3293872980485;4436.268015777425;4478.820847166109;4541.591256786872;4565.315202719586;80034.5998270005;80478.19521804707;80577.59711201406;80687;81387;10308.742281442874;10490.632827850988;10491.580336786596;10507.737306567396;10798.244122911477;19745.40763777089;19959.60080798376;19967.900160499106;19975.101120449614;20325.898370508054;7699.961500192575;7768.2620151086685;7805;7971.60141992898;7975.8404831903035;4381.123775244963;4446;4455.064436468378;4469.44131983502;4680.485146633868;3266.6733326667145;3422.8973130806116;3424.9828750856086;3426.3832510148413;3432.1934345428804;564.520157865819;639.280809089774;649.3474058571106;651.3453978751324;665.0755449924609;534.1160379571814;545.4654438650145;610.7953835465169;648.8029585414902;672.67711498481;8623.137686231452;8976.326775491863;9037.68368107113;9133.45199288045;9211.568938193292;551.6855392426279;581.8080033588906;590.8818236352747;654.2312782480566;659.980200593983;3609;3912.4326972589383;4015.397690346413;4088.3867419887256;4131.26486875513;77563.61218193985;77587;78259;79799.803002955;84580.57709711535;73374.2662573378;75253.62373188099;75361.62319188369;75872;77361.22638773653;68809;69495.65252173707;69802.95295570671;70006.64996674984;70444.23889402807;7212.855742885008;8251.628676709683;8317.41778075529;8342.957130358705;8631.654733810577;42171;42407;42854;43295.783521082194;43552;45324.77337613291;59597.404025959186;59626.10560841678;60218.69890650519;60470.69764651325;11445.313281203154;11564;12404.56584019555;12516.374181291081;12686.048546358876;14123.364448600043;14262.358193881508;14420.927895360455;14425.206613635932;14657.340419681354;13057.608271751482;13121.359830021247;13191.538296159588;13601.319934003459;13788.172709637454;13088.476460941456;13165.407556659782;13177;13187.142835715647;13250.403731831899;12730.554430594884;12730.618081457571;12876.29070691047;13010.219386836441;13132.277724725232;10927.096460502014;11037.448127593749;11080.279781814157;11332.830007549728;11440.94279528597;10590.523426445674;11126.16418290973;11359.432028398713;11369.374684392438;11393.373364465035 19:14:27 INFO - PROCESS | 1674 | __end_tp_report 19:14:27 INFO - PROCESS | 1674 | __start_cc_report 19:14:27 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1353 19:14:27 INFO - PROCESS | 1674 | __end_cc_report 19:14:27 INFO - PROCESS | 1674 | __startTimestamp1464747267792__endTimestamp 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | ------- Summary: start ------- 19:14:27 INFO - PROCESS | 1674 | Number of tests: 6 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5592.61 Median:5788.42 stddev:635.13 (11.0%) stddev-sans-first:602.72 19:14:27 INFO - PROCESS | 1674 | Values: 2965.7 3278.8 3371.6 3375.2 3444.2 5462.8 5496.2 5554.8 5570.9 5600.7 5540.0 5581.7 5597.6 5601.0 5606.7 5030.3 5076.5 5095.4 5157.9 5162.9 5084.6 5173.2 5184.5 5187.5 5195.8 5028.3 5046.8 5065.8 5073.8 5166.7 5125.6 5127.8 5139.0 5150.3 5196.5 4963.0 5013.7 5014.0 5024.7 5035.2 5364.6 5370.2 5411.8 5462.4 5473.1 6070.7 6086.5 6092.2 6092.4 6119.3 5114.7 5963.5 6078.5 6144.3 6183.7 5553.4 5590.6 5614.8 5633.7 5639.4 6197.9 6234.6 6236.5 6248.7 6268.7 5697.8 5816.5 5867.2 5871.9 5948.3 6112.0 6115.3 6132.8 6133.1 6134.2 6022.5 6024.9 6031.1 6038.0 6045.6 5878.0 5940.9 5955.7 6000.5 6026.0 4822.1 4828.2 4879.5 4880.9 4884.2 4330.1 4376.3 4405.2 4418.9 4430.5 5608.4 5609.3 5649.9 5656.9 5667.4 6159.2 6165.9 6181.0 6187.4 6188.8 5357.9 5415.5 5458.3 5576.8 5604.4 4441.8 5216.5 5281.8 5309.7 5314.1 6313.7 6347.1 6381.1 6389.9 6392.7 6156.8 6159.8 6167.0 6168.1 6168.7 6222.3 6240.6 6295.2 6321.8 6327.5 6000.0 6003.7 6005.7 6017.8 6053.9 5799.0 5978.1 6118.4 6127.4 6139.0 4567.4 5831.3 5851.1 5915.2 5920.7 5885.5 5917.4 5927.6 5931.1 5937.0 5458.9 5770.1 5788.4 5810.5 5839.4 5752.2 5838.0 5855.8 5873.2 5908.2 5787.4 5791.4 5797.7 5803.8 5813.0 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:69816.96 Median:38478.21 stddev:150569.27 (391.3%) stddev-sans-first:150901.71 19:14:27 INFO - PROCESS | 1674 | Values: 501.6 505.3 509.3 512.5 518.7 25168.6 31140.2 32854.7 32868.0 32883.2 33115.3 33224.7 34065.3 43666.6 45087.8 9584.7 9762.9 9776.6 9792.3 9803.7 9487.4 9637.2 9638.1 9645.7 9654.2 7413.0 8780.8 11472.0 11683.9 11752.9 11756.6 11868.1 11881.3 11890.4 11892.0 8565.6 11568.5 11617.4 11740.4 11745.9 41271.0 41784.8 41806.6 41906.6 41947.8 3445.8 3463.7 3508.0 3510.6 3838.3 28043.7 31672.7 32461.0 32974.8 32990.5 2846.3 2967.6 3286.5 3296.9 3386.7 512967.0 514414.0 514425.0 516223.4 516463.0 64962.7 65200.0 65298.7 65331.0 65459.0 68523.3 68554.7 68575.7 68583.3 68766.5 16492.2 16623.5 16652.3 16696.7 16712.0 13120.6 13194.9 13201.9 13261.0 13317.0 15117.7 15839.4 16217.4 16258.8 18383.9 5960.3 7154.5 7256.9 7313.6 7324.3 889.1 939.9 1081.0 1083.5 1090.1 30828.7 30884.4 31173.7 31417.9 31984.4 1082.9 1164.8 1168.9 1204.7 1241.4 15724.3 15824.8 15827.1 16175.0 16267.3 552950.0 629667.0 631028.0 631538.0 631681.0 52808.0 60894.7 61386.4 61390.4 61547.4 1421.2 1437.1 1447.6 1452.0 1452.7 31835.5 31938.4 32161.3 32729.5 33018.5 53384.2 53845.0 53916.5 54026.6 54040.6 406804.0 413500.0 415290.0 594961.0 821423.0 40325.0 41010.6 41436.6 41496.8 41658.4 48278.5 48552.3 48623.5 49457.8 49521.5 37923.8 38478.0 38562.0 39635.8 40545.0 38288.8 38740.0 38807.4 39636.8 40198.4 38478.4 38513.6 40110.6 40123.8 41797.6 33386.2 36183.3 36232.8 36390.8 36557.0 20561.6 23081.2 23111.9 24574.4 25453.2 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:35229.29 Median:2438.04 stddev:99646.52 (4087.2%) stddev-sans-first:99891.70 19:14:27 INFO - PROCESS | 1674 | Values: 380.4 385.2 386.7 398.5 403.4 19390.8 21246.6 21376.7 21626.9 21626.9 20821.7 20864.9 21646.1 21831.2 21864.9 5690.3 5798.2 5975.2 6005.1 6019.4 4172.4 4389.8 5897.6 6306.9 6653.9 6519.9 6633.0 6638.7 6639.2 6649.0 6425.2 6595.3 6625.7 6646.8 6651.2 3642.5 3828.0 5335.9 5652.3 5679.7 25121.0 25809.9 26004.3 26290.9 26415.2 2236.2 2264.3 2417.7 2458.3 2560.9 14996.8 15340.7 15427.2 15939.0 15991.7 1820.3 1821.3 1873.2 1879.6 1930.8 78077.6 117080.4 118465.4 119146.0 122860.4 8913.5 10412.6 10593.9 10774.4 10836.0 13368.1 13437.8 13458.4 13460.4 13462.5 8344.2 8532.1 8556.0 8654.1 8672.9 8595.8 8602.7 8647.9 8741.4 8767.3 5799.8 6193.9 6585.5 6588.4 6671.7 1678.2 1751.6 1803.5 1865.9 1869.4 821.6 929.3 967.7 983.2 1031.0 18031.8 20788.1 21507.1 21714.8 22550.8 938.8 949.7 970.3 978.7 995.5 9357.7 11170.1 11227.4 11253.0 11380.5 579280.0 592077.0 598184.0 604520.0 606810.0 104525.0 143043.9 144187.0 144419.0 146488.0 1031.0 1036.1 1058.2 1058.8 1059.0 18180.5 18420.7 18424.6 18430.9 18850.2 51437.7 52998.5 53473.0 55742.7 56882.0 76526.6 77188.5 77358.6 77995.8 78803.2 6998.4 7095.6 8196.0 8230.2 8286.3 10166.7 12725.4 12792.0 12924.5 13073.2 10976.6 11011.8 11054.0 11061.2 11362.8 10694.0 10791.6 11002.7 11014.9 11047.8 11271.3 11293.9 11444.5 11535.8 11755.9 7119.6 7246.1 7478.7 7550.0 8441.3 10047.2 11459.9 11600.8 11609.3 11683.4 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5691.74 Median:4775.71 stddev:3222.97 (67.5%) stddev-sans-first:3215.73 19:14:27 INFO - PROCESS | 1674 | Values: 1381.5 1556.2 1563.0 1825.2 1965.4 8007.2 8647.9 8907.3 8927.0 9034.6 8223.8 8831.0 8857.4 8873.9 8881.4 2357.3 2386.9 2391.5 2391.8 2393.7 2343.0 2371.1 2388.3 2391.7 2393.4 2350.1 2361.6 2370.9 2383.0 2384.3 2327.0 2358.6 2364.6 2365.8 2372.6 1950.8 2258.5 2361.6 2361.7 2362.5 7346.2 7594.2 7962.8 8808.5 8901.0 5164.6 5399.3 5409.4 5431.1 5434.1 3578.2 3602.1 3710.9 3711.9 3718.2 1074.8 1093.5 1096.1 1103.3 1104.4 11551.4 11776.4 11782.9 11790.2 11792.3 4693.1 4704.6 4824.0 4828.4 4834.1 10222.8 10238.7 10249.2 10294.1 10294.4 5408.0 5420.1 5422.1 5429.2 5429.7 4062.8 4738.8 4940.9 4941.9 4946.6 3262.4 3796.1 3866.2 3885.8 3940.6 991.2 1178.5 1181.4 1191.3 1192.9 839.9 853.8 878.0 926.9 959.1 5832.6 5878.6 5949.9 6066.5 6177.9 432.9 434.5 452.4 452.6 453.4 4079.2 4087.7 4138.0 4145.2 4147.6 10492.1 10775.2 10817.7 10870.5 10907.9 10848.0 10861.9 10870.4 10871.4 10878.0 7774.1 8230.8 8267.9 8307.3 8326.5 5951.1 6084.6 6104.9 6270.8 6324.2 8669.7 8983.6 9796.8 9816.9 10017.4 10257.9 10324.9 10379.9 10398.4 10407.3 6026.0 7444.0 7447.0 7471.5 7521.9 8085.8 8116.5 8191.0 8199.4 8212.1 7261.8 7411.4 7444.8 7447.0 7601.2 7168.6 7253.7 7259.7 7352.9 7384.7 6874.6 6919.8 6949.3 6970.4 6984.1 4900.6 4950.7 4990.3 5018.3 5108.9 4507.8 4615.8 4812.6 4891.8 5070.1 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4385.59 Median:4738.65 stddev:1354.22 (28.6%) stddev-sans-first:1323.03 19:14:27 INFO - PROCESS | 1674 | Values: 310.6 317.2 321.0 323.8 330.1 5056.0 5132.3 5204.6 5357.7 5480.2 4282.7 4717.8 5396.6 5418.1 5460.3 3153.0 4001.4 4021.5 4025.3 4053.2 3655.5 3712.9 3792.0 3902.7 4043.0 2997.9 3778.6 3879.3 3972.5 4008.9 3896.3 4018.2 4020.1 4069.7 4109.6 2829.8 3540.8 3541.9 3568.0 3586.4 4596.7 4611.8 4801.7 5088.9 5163.8 4444.6 4624.8 4641.9 4656.7 4666.9 5311.7 5511.7 5515.2 5552.3 5667.9 3462.0 3867.0 3895.8 3929.6 3938.6 4989.4 5402.5 5470.8 5822.6 5897.9 3516.5 3956.3 3996.4 4007.3 4013.2 3217.5 3705.4 4036.4 4088.2 4089.9 4642.0 4649.0 4654.7 4656.2 4737.9 4679.5 5124.8 5408.8 5628.0 5689.4 1367.7 1374.4 1381.7 1392.7 1396.7 786.0 817.2 849.6 862.4 876.9 4200.3 4527.0 4671.0 4906.6 5022.1 4987.2 5232.8 5286.5 5801.7 5862.0 4796.7 4885.0 5169.4 5214.2 5221.1 2699.8 2796.7 2802.3 2982.4 3005.3 5075.8 5178.0 5818.0 5861.5 6001.5 5425.9 5795.2 5917.2 5963.7 5991.6 5213.9 5437.0 5500.8 5984.8 6008.8 4973.7 5402.3 5419.0 5818.5 5847.7 4688.9 5074.2 5769.9 5790.8 5794.0 5142.6 5671.7 5934.4 6082.0 6103.2 3011.5 3067.9 3278.9 3313.4 3367.4 4173.0 4367.0 4828.6 4978.9 4990.6 3994.0 4336.2 4419.6 4493.5 4765.5 4452.3 4468.6 4545.0 4759.9 4769.6 4367.0 4732.0 4739.4 5083.7 5573.2 5104.6 5415.8 5530.9 5538.8 5566.0 4289.7 5341.9 5448.0 5465.6 5647.9 19:14:27 INFO - PROCESS | 1674 | 19:14:27 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:17808.10 Median:4474.13 stddev:23617.97 (527.9%) stddev-sans-first:23649.23 19:14:27 INFO - PROCESS | 1674 | Values: 685.7 718.3 762.7 779.8 827.9 14586.6 15268.1 15276.6 15279.6 15282.9 11921.6 13828.4 13870.8 14811.6 15360.5 909.3 915.9 919.5 923.2 924.0 919.6 923.4 923.7 924.3 927.9 941.4 945.4 947.0 949.4 952.1 942.2 943.4 943.9 945.1 948.9 700.6 704.2 704.9 705.3 706.2 14772.5 14786.7 14808.1 14812.9 14849.9 11395.9 12405.1 12947.0 13031.8 13245.0 11990.6 12235.5 12244.9 12349.8 12479.9 4191.3 4436.3 4478.8 4541.6 4565.3 80034.6 80478.2 80577.6 80687.0 81387.0 10308.7 10490.6 10491.6 10507.7 10798.2 19745.4 19959.6 19967.9 19975.1 20325.9 7700.0 7768.3 7805.0 7971.6 7975.8 4381.1 4446.0 4455.1 4469.4 4680.5 3266.7 3422.9 3425.0 3426.4 3432.2 564.5 639.3 649.3 651.3 665.1 534.1 545.5 610.8 648.8 672.7 8623.1 8976.3 9037.7 9133.5 9211.6 551.7 581.8 590.9 654.2 660.0 3609.0 3912.4 4015.4 4088.4 4131.3 77563.6 77587.0 78259.0 79799.8 84580.6 73374.3 75253.6 75361.6 75872.0 77361.2 68809.0 69495.7 69803.0 70006.6 70444.2 7212.9 8251.6 8317.4 8343.0 8631.7 42171.0 42407.0 42854.0 43295.8 43552.0 45324.8 59597.4 59626.1 60218.7 60470.7 11445.3 11564.0 12404.6 12516.4 12686.0 14123.4 14262.4 14420.9 14425.2 14657.3 13057.6 13121.4 13191.5 13601.3 13788.2 13088.5 13165.4 13177.0 13187.1 13250.4 12730.6 12730.6 12876.3 13010.2 13132.3 10927.1 11037.4 11080.3 11332.8 11440.9 10590.5 11126.2 11359.4 11369.4 11393.4 19:14:27 INFO - PROCESS | 1674 | -------- Summary: end -------- 19:14:27 INFO - PROCESS | 1674 | 19:14:28 INFO - TEST-INFO | 1674: exit 0 19:14:28 INFO - TEST-OK | dromaeo_css | took 1115959ms 19:14:28 INFO - TEST-START | kraken 19:14:28 INFO - operating with platform_type : mac_ 19:14:28 INFO - Initialising browser for kraken test... 19:14:28 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpTrJdGl/profile http://localhost:49271/getInfo.html) 19:14:30 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 19:14:30 INFO - PROCESS | 1679 | colorDepth:24 19:14:30 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 19:14:30 INFO - PROCESS | 1679 | __metrics 19:14:30 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:14:30 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:14:31 INFO - TEST-INFO | 1679: exit 0 19:14:31 INFO - Browser initialized. 19:14:31 INFO - Running cycle 1/1 for kraken test... 19:14:31 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpTrJdGl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:14:43 INFO - PROCESS | 1680 | RSS: Main: 241930240 19:14:43 INFO - PROCESS | 1680 | 19:14:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 19:14:47 INFO - PROCESS | 1680 | RSS: Main: 291946496 19:14:47 INFO - PROCESS | 1680 | 19:14:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 19:14:50 INFO - PROCESS | 1680 | RSS: Main: 301916160 19:14:50 INFO - PROCESS | 1680 | 19:14:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 19:14:54 INFO - PROCESS | 1680 | RSS: Main: 386080768 19:14:54 INFO - PROCESS | 1680 | 19:14:56 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 19:14:57 INFO - PROCESS | 1680 | RSS: Main: 287342592 19:14:57 INFO - PROCESS | 1680 | 19:14:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:15:00 INFO - PROCESS | 1680 | RSS: Main: 291016704 19:15:00 INFO - PROCESS | 1680 | 19:15:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 19:15:03 INFO - PROCESS | 1680 | RSS: Main: 328601600 19:15:03 INFO - PROCESS | 1680 | 19:15:06 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 19:15:07 INFO - PROCESS | 1680 | RSS: Main: 330412032 19:15:07 INFO - PROCESS | 1680 | 19:15:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 19:15:10 INFO - PROCESS | 1680 | RSS: Main: 328847360 19:15:10 INFO - PROCESS | 1680 | 19:15:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:15:12 INFO - PROCESS | 1680 | RSS: Main: 310456320 19:15:12 INFO - PROCESS | 1680 | 19:15:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:15:15 INFO - PROCESS | 1680 | RSS: Main: 316387328 19:15:15 INFO - PROCESS | 1680 | 19:15:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:15:18 INFO - PROCESS | 1680 | RSS: Main: 321130496 19:15:18 INFO - PROCESS | 1680 | 19:15:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:15:22 INFO - PROCESS | 1680 | RSS: Main: 324141056 19:15:22 INFO - PROCESS | 1680 | 19:15:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:15:25 INFO - PROCESS | 1680 | RSS: Main: 323608576 19:15:25 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 19:15:27 INFO - PROCESS | 1680 | RSS: Main: 366862336 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | __start_tp_report 19:15:27 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 19:15:27 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 19:15:27 INFO - PROCESS | 1680 | |i|pagename|runs| 19:15:27 INFO - PROCESS | 1680 | |0;ai-astar;113;106;111;110;112;112;113;157;164;137 19:15:27 INFO - PROCESS | 1680 | |1;audio-beat-detection;153;126;117;168;119;117;137;118;154;117 19:15:27 INFO - PROCESS | 1680 | |2;audio-dft;184;183;181;182;183;184;182;177;181;180 19:15:27 INFO - PROCESS | 1680 | |3;audio-fft;78;74;57;117;49;69;88;89;97;55 19:15:27 INFO - PROCESS | 1680 | |4;audio-oscillator;84;88;77;70;57;89;117;102;99;131 19:15:27 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;87;84;86;83;96;131;87;84;86;83 19:15:27 INFO - PROCESS | 1680 | |6;imaging-darkroom;136;151;94;94;93;94;94;93;92;94 19:15:27 INFO - PROCESS | 1680 | |7;imaging-desaturate;84;87;84;84;84;83;84;98;138;138 19:15:27 INFO - PROCESS | 1680 | |8;json-parse-financial;53;53;54;53;55;55;53;53;54;56 19:15:27 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;67;66;72;75;74;96;100;101;72;70 19:15:27 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;87;85;85;86;102;87;86;86;144;85 19:15:27 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;146;148;142;137;138;134;126;175;168;150 19:15:27 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;165;166;165;166;168;165;165;164;160;163 19:15:27 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;65;66;67;65;65;64;66;66;64;66 19:15:27 INFO - PROCESS | 1680 | __end_tp_report 19:15:27 INFO - PROCESS | 1680 | __start_cc_report 19:15:27 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1944 19:15:27 INFO - PROCESS | 1680 | __end_cc_report 19:15:27 INFO - PROCESS | 1680 | __startTimestamp1464747327546__endTimestamp 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | ------- Summary: start ------- 19:15:27 INFO - PROCESS | 1680 | Number of tests: 14 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:123.50 Median:113.00 stddev:21.28 (18.8%) stddev-sans-first:22.23 19:15:27 INFO - PROCESS | 1680 | Values: 113.0 106.0 111.0 110.0 112.0 112.0 113.0 157.0 164.0 137.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:131.50 stddev:19.20 (14.6%) stddev-sans-first:18.89 19:15:27 INFO - PROCESS | 1680 | Values: 153.0 126.0 117.0 168.0 119.0 117.0 137.0 118.0 154.0 117.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:181.70 Median:182.50 stddev:2.11 (1.2%) stddev-sans-first:2.07 19:15:27 INFO - PROCESS | 1680 | Values: 184.0 183.0 181.0 182.0 183.0 184.0 182.0 177.0 181.0 180.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:77.30 Median:76.00 stddev:21.10 (27.8%) stddev-sans-first:22.38 19:15:27 INFO - PROCESS | 1680 | Values: 78.0 74.0 57.0 117.0 49.0 69.0 88.0 89.0 97.0 55.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:80.50 stddev:21.89 (27.2%) stddev-sans-first:23.06 19:15:27 INFO - PROCESS | 1680 | Values: 84.0 88.0 77.0 70.0 57.0 89.0 117.0 102.0 99.0 131.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:86.00 stddev:14.65 (17.0%) stddev-sans-first:15.48 19:15:27 INFO - PROCESS | 1680 | Values: 87.0 84.0 86.0 83.0 96.0 131.0 87.0 84.0 86.0 83.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:94.00 stddev:21.39 (22.8%) stddev-sans-first:19.18 19:15:27 INFO - PROCESS | 1680 | Values: 136.0 151.0 94.0 94.0 93.0 94.0 94.0 93.0 92.0 94.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:84.00 stddev:22.36 (26.6%) stddev-sans-first:23.26 19:15:27 INFO - PROCESS | 1680 | Values: 84.0 87.0 84.0 84.0 84.0 83.0 84.0 98.0 138.0 138.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:1.12 19:15:27 INFO - PROCESS | 1680 | Values: 53.0 53.0 54.0 53.0 55.0 55.0 53.0 53.0 54.0 56.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:72.00 stddev:13.93 (19.3%) stddev-sans-first:14.04 19:15:27 INFO - PROCESS | 1680 | Values: 67.0 66.0 72.0 75.0 74.0 96.0 100.0 101.0 72.0 70.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:86.00 stddev:18.54 (21.6%) stddev-sans-first:19.52 19:15:27 INFO - PROCESS | 1680 | Values: 87.0 85.0 85.0 86.0 102.0 87.0 86.0 86.0 144.0 85.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:147.00 stddev:15.09 (10.3%) stddev-sans-first:16.00 19:15:27 INFO - PROCESS | 1680 | Values: 146.0 148.0 142.0 137.0 138.0 134.0 126.0 175.0 168.0 150.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:165.00 stddev:2.11 (1.3%) stddev-sans-first:2.24 19:15:27 INFO - PROCESS | 1680 | Values: 165.0 166.0 165.0 166.0 168.0 165.0 165.0 164.0 160.0 163.0 19:15:27 INFO - PROCESS | 1680 | 19:15:27 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 19:15:27 INFO - PROCESS | 1680 | Values: 65.0 66.0 67.0 65.0 65.0 64.0 66.0 66.0 64.0 66.0 19:15:27 INFO - PROCESS | 1680 | -------- Summary: end -------- 19:15:27 INFO - PROCESS | 1680 | 19:15:28 INFO - TEST-INFO | 1680: exit 0 19:15:28 INFO - TEST-OK | kraken | took 60079ms 19:15:28 INFO - SUITE-END | took 1176s 19:15:28 INFO - Completed test suite (00:19:36) 19:15:28 INFO - javascript benchmark 19:15:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2965.703429657033, 3278.786878852876, 3371.6122645895716, 3375.2236985493337, 3444.2422667013266, 5462.808801691942, 5496.230527726117, 5554.833354999351, 5570.916436253455, 5600.747966341514, 5540.0, 5581.748821303041, 5597.608167428281, 5600.971995140028, 5606.747696353664, 5030.320906677597, 5076.46697096805, 5095.439501654815, 5157.948420515784, 5162.8709282268055, 5084.644074914758, 5173.249878767577, 5184.481551844804, 5187.455317191697, 5195.818146364878, 5028.346314979048, 5046.82336118236, 5065.822696205633, 5073.822416215433, 5166.741662916858, 5125.589952803785, 5127.7948882044675, 5139.0, 5150.330457040598, 5196.454372290893, 4963.032208719301, 5013.724245166514, 5013.974930125362, 5024.723640199788, 5035.244713292998, 5364.624476286662, 5370.167624018283, 5411.756470958816, 5462.399136095027, 5473.124300111963, 6070.660461235068, 6086.482648974842, 6092.1775560299675, 6092.3564182880355, 6119.296280927706, 5114.693118412907, 5963.493103086243, 6078.544109191784, 6144.324124346318, 6183.6598987055695, 5553.389127196006, 5590.552755779528, 5614.803481878155, 5633.746481408264, 5639.351474580394, 6197.876042479215, 6234.594751341148, 6236.532260080512, 6248.656323902138, 6268.655223962726, 5697.800576979786, 5816.534677225812, 5867.237259156368, 5871.911921321183, 5948.256467941507, 6111.96944015277, 6115.312191768499, 6132.846678833065, 6133.110698948717, 6134.202553667995, 6022.518198544106, 6024.8795024100145, 6031.125486804389, 6037.969810151009, 6045.637261764308, 5878.030125029408, 5940.851478713067, 5955.702214889238, 6000.519958403404, 6025.969870150621, 4822.132016237111, 4828.203346447867, 4879.512048795092, 4880.926786098212, 4884.169691152512, 4330.112326972963, 4376.256036473807, 4405.207062728739, 4418.867433976986, 4430.534793846664, 5608.439156084441, 5609.298837645294, 5649.915251271234, 5656.85857853557, 5667.376588575254, 6159.168512250879, 6165.876682466415, 6180.980138277134, 6187.412195841388, 6188.81433556994, 5357.919631205535, 5415.48552887475, 5458.263134476795, 5576.776928922879, 5604.41153678866, 4441.777911104432, 5216.452272511332, 5281.841544753664, 5309.7079660618265, 5314.123169677037, 6313.715882785285, 6347.143135676624, 6381.1066450696935, 6389.93610063912, 6392.7123279452535, 6156.846078848064, 6159.846003849761, 6166.969165154325, 6168.136460895568, 6168.691565421801, 6222.253329600296, 6240.594361366497, 6295.150154729053, 6321.841953951188, 6327.525435592441, 5999.98000339952, 6003.729832157651, 6005.699715014319, 6017.759289628366, 6053.878922421615, 5799.043157879071, 5978.103284507359, 6118.388161183846, 6127.417895299939, 6139.017757159012, 4567.38340324052, 5831.329397119385, 5851.093080572518, 5915.201447804491, 5920.674362910081, 5885.499732522612, 5917.437843404869, 5927.614705044159, 5931.110333449845, 5936.970315148396, 5458.91811622818, 5770.13447982806, 5788.421157884178, 5810.477057064884, 5839.35767065614, 5752.223449834219, 5837.970810145922, 5855.824325270249, 5873.177755114373, 5908.172855800278, 5787.36339002701, 5791.420857914175, 5797.681127538026, 5803.7968671096305, 5813.0], "value": 5552.469150227718, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.5511117549793, 505.25979440120216, 509.2666560153379, 512.5310341037952, 518.6991544903956, 25168.622470662933, 31140.22149446265, 32854.67145328549, 32868.0, 32883.177920552, 33115.33769324618, 33224.66775332246, 34065.31869362611, 43666.56333436665, 45087.77456112723, 9584.712458626253, 9762.90237097627, 9776.560054797552, 9792.314537982344, 9803.656872009482, 9487.383320084207, 9637.22902167825, 9638.132568068873, 9645.71062868115, 9654.227661787076, 7412.962935185316, 8780.780480488007, 11472.0, 11683.941580292087, 11752.882471175264, 11756.647300580953, 11868.109891758153, 11881.287122772661, 11890.405479726065, 11892.04863610909, 8565.614547345385, 11568.479418426192, 11617.4191290436, 11740.412979350998, 11745.882541174564, 41271.0, 41784.79107604443, 41806.581934180576, 41906.58093419057, 41947.7902610488, 3445.758796884219, 3463.705585025276, 3507.9824600877087, 3510.6313837047255, 3838.309104361214, 28043.71956280452, 31672.733090676345, 32461.0, 32974.83512582446, 32990.50514242288, 2846.274200078987, 2967.55486676998, 3286.5234540991423, 3296.917577060569, 3386.7290616750847, 512967.0, 514414.0, 514425.0, 516223.41888290696, 516463.0, 64962.67518662376, 65200.0, 65298.69402612016, 65331.0, 65459.0, 68523.3147668527, 68554.65722671355, 68575.65712171407, 68583.31416685869, 68766.49868010548, 16492.17539123039, 16623.50129496117, 16652.250648720837, 16696.74954875678, 16711.99728016323, 13120.60638180856, 13194.934025329812, 13201.943844492418, 13261.005424593193, 13317.0, 15117.714994225615, 15839.445619403496, 16217.351305947866, 16258.75611865823, 18383.90808045951, 5960.344362120163, 7154.4634152438775, 7256.8911466328045, 7313.634318284171, 7324.267573242633, 889.1242126505387, 939.8684184214216, 1080.9675709728722, 1083.4691001409253, 1090.0516550601028, 30828.691713082582, 30884.382312353628, 31173.688263117532, 31417.900373486817, 31984.360312793615, 1082.8917108289265, 1164.8194529847888, 1168.8889555492215, 1204.740980689156, 1241.3979220078254, 15724.29240684172, 15824.76262856058, 15827.050376977419, 16175.0, 16267.267972941247, 552950.0, 629667.0, 631028.0, 631538.0, 631681.0495685136, 52808.0, 60894.69552652208, 61386.38613613896, 61390.38609613936, 61547.38452615506, 1421.232534431416, 1437.0730878583256, 1447.5669087602987, 1452.0489079652843, 1452.7167202395642, 31835.522467162547, 31938.36123277568, 32161.26629236541, 32729.50905736368, 33018.504722428705, 53384.19923701225, 53845.0, 53916.460835391146, 54026.64933376697, 54040.64898377572, 406804.0, 413500.0, 415290.0, 594961.0, 821423.0, 40325.0, 41010.589894100674, 41436.58563414448, 41496.792516037225, 41658.375124373764, 48278.5172148274, 48552.27171592358, 48623.5137648619, 49457.752711236215, 49521.50478495169, 37923.81038094792, 38478.0, 38562.0, 39635.80182099187, 40544.98637534085, 38288.80855595704, 38740.0, 38807.41788873113, 39636.80181599189, 40198.397024044076, 38478.42282365711, 38513.614863851006, 40110.598894011855, 40123.79938100291, 41797.58202417937, 33386.165345866546, 36183.27633447263, 36232.81883590565, 36390.81804591066, 36557.0, 20561.58876822485, 23081.192158274378, 23111.88444057769, 24574.385640358418, 25453.236402907944], "value": 20579.54991792713, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.40466669661976, 385.18194123735975, 386.6558762701196, 398.5436675007119, 403.43720509888703, 19390.806091939077, 21246.57506849862, 21376.679349809765, 21626.89186554065, 21626.89186554067, 20821.687674684854, 20864.895675521602, 21646.134154633797, 21831.235906743266, 21864.89067554664, 5690.317161940573, 5798.246227990356, 5975.193348897887, 6005.069214271794, 6019.3980601939675, 4172.436721042651, 4389.824407023715, 5897.616654917417, 6306.905396419057, 6653.866922661544, 6519.869602607945, 6633.0, 6638.701258443381, 6639.170103737033, 6649.0, 6425.1968503937005, 6595.340465953414, 6625.701843417034, 6646.833829154261, 6651.235107962573, 3642.508261384732, 3827.9617203827884, 5335.893282134374, 5652.34997975234, 5679.744411501492, 25120.99516019357, 25809.870950645316, 26004.34989125268, 26290.868545657147, 26415.20754377372, 2236.1837929155904, 2264.3320220534815, 2417.721961974378, 2458.3485376375274, 2560.8591527465983, 14996.775048374284, 15340.693186136326, 15427.228638568138, 15939.0, 15991.680166396607, 1820.25369598464, 1821.330466977198, 1873.2226126157627, 1879.6334714730633, 1930.7683078030727, 78077.60961195214, 117080.41459792732, 118465.40767296194, 119146.0, 122860.38569807183, 8913.5097569633, 10412.635557755442, 10593.89406105929, 10774.407407592658, 10836.0, 13368.131071480517, 13437.798433023512, 13458.394372253271, 13460.394282257323, 13462.528811491551, 8344.207300306583, 8532.146785321542, 8556.0, 8654.134586541422, 8672.913270867211, 8595.796588477619, 8602.698905538276, 8647.870281945776, 8741.388102832869, 8767.25478334336, 5799.782045770351, 6193.893638502199, 6585.473162147016, 6588.439982601437, 6671.733130674816, 1678.202853644509, 1751.59713265947, 1803.5310819186843, 1865.869389142774, 1869.4298239036953, 821.5727821532889, 929.3215952354819, 967.7338731848798, 983.2036050798876, 1030.9742256443649, 18031.819681803277, 20788.064537095866, 21507.13971441125, 21714.82851714816, 22550.774492255197, 938.8169306985072, 949.7483166960761, 970.3304719743354, 978.7357413498459, 995.5022488755623, 9357.719268421959, 11170.101292350648, 11227.438628068563, 11253.0, 11380.544778208945, 579280.0, 592077.0, 598184.0, 604520.0, 606810.0, 104525.0, 143043.85434218496, 144187.0, 144419.0, 146488.0, 1030.9845352319721, 1036.124474818777, 1058.222206678091, 1058.7564860082225, 1058.957641694339, 18180.545486362418, 18420.6842768303, 18424.631507370046, 18430.89414635126, 18850.24599016024, 51437.7428112857, 52998.470015300896, 53473.0, 55742.72128639331, 56881.99999999834, 76526.6173669128, 77188.4562308762, 77358.6132069336, 77995.83006255025, 78803.21196787959, 6998.370146686822, 7095.645217739196, 8196.0, 8230.21812927771, 8286.295664868554, 10166.745831354276, 12725.363731813557, 12792.040596955452, 12924.483020679067, 13073.215607063226, 10976.560937562408, 11011.779764404828, 11054.0, 11061.225714199929, 11362.772744545227, 10694.037536621741, 10791.6222932197, 11002.669919902415, 11014.889851101387, 11047.779044419227, 11271.323720576791, 11293.887061129284, 11444.484998174936, 11535.826962595736, 11755.88244117548, 7119.576084782961, 7246.130464344313, 7478.738244161428, 7549.980752598329, 8441.32469402434, 10047.246456515937, 11459.885401145882, 11600.825987610024, 11609.30344179352, 11683.415829208336], "value": 9774.69725873037, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.502659042745, 1556.1674504140283, 1562.9765553516695, 1825.2151574822822, 1965.4103768869345, 8007.159248278934, 8647.870281945769, 8907.287417006624, 8926.973398059225, 9034.638614455414, 8223.753287401387, 8830.984436789755, 8857.379983401164, 8873.866891996626, 8881.422707524007, 2357.2810292860713, 2386.868722220277, 2391.5097405032016, 2391.796697280733, 2393.74865639108, 2343.027643527934, 2371.134535894395, 2388.3312672451743, 2391.7369089400154, 2393.437542177596, 2350.1069593554425, 2361.633946738258, 2370.9288721338303, 2382.9880850595723, 2384.260879127476, 2326.9883650581637, 2358.596635002171, 2364.5507353602848, 2365.822563307742, 2372.6203807390734, 1950.8439324854119, 2258.5482903419215, 2361.5867223235864, 2361.6693662887037, 2362.4566349739484, 7346.191918888918, 7594.240575942362, 7962.800929976739, 8808.471491710518, 8900.976387715431, 5164.561012313958, 5399.2980912481125, 5409.404965453797, 5431.131019036935, 5434.130539113723, 3578.1949061461223, 3602.0814692253307, 3710.888673339804, 3711.851525938959, 3718.219173973469, 1074.790415868906, 1093.4532733633184, 1096.1450068946156, 1103.3324838472688, 1104.3539529375325, 11551.364674942875, 11776.411179440993, 11782.941085294518, 11790.174687771774, 11792.29246245228, 4693.108309421199, 4704.623630109583, 4823.975880120647, 4828.420589529279, 4834.129856625771, 10222.846657300146, 10238.692839214835, 10249.231307651957, 10294.073533382032, 10294.433806140734, 5408.0, 5420.105682562412, 5422.132458806651, 5429.158480435539, 5429.728513574306, 4062.7968601569805, 4738.786754596052, 4940.92588611164, 4941.901161976813, 4946.604271658259, 3262.4127657021963, 3796.1079146401125, 3866.1881004988704, 3885.7862817545306, 3940.625640564142, 991.177322822056, 1178.452019810775, 1181.4329122021477, 1191.255465334166, 1192.8866757658009, 839.8992120945403, 853.8463076646264, 877.9692710755093, 926.9258459323238, 959.1463597398321, 5832.62087964281, 5878.617889837147, 5949.940500595025, 6066.48434883031, 6177.938220617736, 432.91125319309464, 434.45258973692745, 452.3757215043219, 452.590405682851, 453.4332084893883, 4079.2453396121814, 4087.6729861611, 4137.97931010349, 4145.150244199932, 4147.6267135957905, 10492.108170805413, 10775.245732798785, 10817.729556761144, 10870.456477176109, 10907.945460272647, 10848.02367786903, 10861.891381086247, 10870.402127883042, 10871.402072886067, 10878.02097811185, 7774.144844067261, 8230.83538329242, 8267.875981860278, 8307.252347288773, 8326.5004099753, 5951.137085122719, 6084.602402363732, 6104.9389506106145, 6270.7805226816845, 6324.177856878577, 8669.653213871627, 8983.595738191667, 9796.804063918538, 9816.85274720894, 10017.39895606266, 10257.897421025695, 10324.896751032695, 10379.896201038195, 10398.376097434179, 10407.271490995558, 6026.0, 7443.962780186064, 7446.962765186139, 7471.476996610185, 7521.887171692539, 8085.757427277191, 8116.513009219466, 8191.017077950684, 8199.44210599984, 8212.13772553897, 7261.818454538678, 7411.370033547209, 7444.776656700307, 7447.031885854804, 7601.163871974189, 7168.641567921687, 7253.673584688807, 7259.709611615476, 7352.926470735224, 7384.667689954073, 6874.587524748531, 6919.792406227822, 6949.30506949301, 6970.407515361251, 6984.057152284377, 4900.632452566, 4950.653454258168, 4990.251462280687, 5018.322526459027, 5108.897822043613, 4507.75207363585, 4615.79228934692, 4812.639052071038, 4891.828785992473, 5070.087384270719], "value": 4487.876578286288, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.5791652311118, 317.23229783922903, 320.9566708494354, 323.75879969422783, 330.1103525997438, 5056.039352523023, 5132.255822905677, 5204.635675502716, 5357.73211339433, 5480.205370221315, 4282.693778397587, 4717.811287548494, 5396.622236443451, 5418.106012507941, 5460.34475862897, 3152.9752830330176, 4001.4397984282077, 4021.517417909855, 4025.2754504189234, 4053.1893621275717, 3655.4516822476576, 3712.9257414851686, 3791.9620803791886, 3902.7121050053556, 4042.959570404302, 2997.9400411991746, 3778.622137786227, 3879.301725689375, 3972.4507442097606, 4008.879733607982, 3896.337622604164, 4018.1963607278517, 4020.0954785173394, 4069.674426045909, 4109.589041095866, 2829.7877659175438, 3540.76984995977, 3541.8937431877084, 3567.9821600892087, 3586.4082426399614, 4596.747178905159, 4611.800931757733, 4801.663883528155, 5088.898222035538, 5163.819266325698, 4444.555544445562, 4624.791884365178, 4641.883952901205, 4656.650751193674, 4666.906661866744, 5311.681299122065, 5511.669299841982, 5515.227868098469, 5552.278203833516, 5667.943320566783, 3462.04793681737, 3866.980665096685, 3895.7662540247675, 3929.5873933236885, 3938.6258305460933, 4989.403390914908, 5402.459754024605, 5470.753816078286, 5822.563307751935, 5897.911531327033, 3516.507688923553, 3956.2680904032845, 3996.3805610130476, 4007.338789099766, 4013.2174226026227, 3217.501287300472, 3705.444183372516, 4036.354183330653, 4088.179156382087, 4089.9386509202386, 4642.0, 4648.953510464852, 4654.650901182357, 4656.161890859677, 4737.881552961204, 4679.532046795362, 5124.846909445419, 5408.783648654088, 5627.971860140673, 5689.374168841424, 1367.692269239433, 1374.4021350712471, 1381.675306303018, 1392.7214557088623, 1396.7066915947562, 785.9567723775248, 817.2440492544373, 849.562475325208, 862.3877047296364, 876.8816209811594, 4200.3456992416795, 4526.977365113154, 4671.0, 4906.55840974314, 5022.096022715873, 4987.177115775857, 5232.790688372423, 5286.497782710637, 5801.7220270547, 5862.0, 4796.712197268175, 4885.0, 5169.353830771153, 5214.243934629458, 5221.086309895829, 2699.824511406752, 2796.734310240524, 2802.2574017885277, 2982.388610334876, 3005.3388254583956, 5075.796968121308, 5178.016176926372, 5817.95276850171, 5861.531077513789, 6001.489873360813, 5425.941941321484, 5795.238141270403, 5917.224832550218, 5963.731632076567, 5991.58058935879, 5213.869653258623, 5436.966976274495, 5500.779968801363, 5984.832957573231, 6008.819735407945, 4973.676711013773, 5402.324709411324, 5419.0, 5818.4763371296785, 5847.707614619337, 4688.9296660549435, 5074.238864170404, 5769.913451298318, 5790.801668790094, 5794.016081718003, 5142.614303927296, 5671.731307477042, 5934.368048786619, 6081.969590152021, 6103.23709536308, 3011.4579375712583, 3067.9386412271183, 3278.885239016623, 3313.4367157582788, 3367.410703126992, 4173.0, 4366.956330436655, 4828.599706085196, 4978.8755281118265, 4990.5508504234795, 3994.0, 4336.1978034063795, 4419.602235798793, 4493.528179541101, 4765.547273009059, 4452.287633978677, 4468.597826195658, 4544.954550454453, 4759.906061060338, 4769.642276829321, 4367.0, 4732.0, 4739.4217365888535, 5083.694978301313, 5573.247611572386, 5104.566111880383, 5415.83752487426, 5530.889382212414, 5538.833834984957, 5565.972170139124, 4289.67827412939, 5341.919871201857, 5448.0, 5465.6335916021, 5647.905268680174], "value": 3966.4337782254133, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.6571714142929, 718.253016862463, 762.6720510180622, 779.7504798464487, 827.8675411934091, 14586.635334116652, 15268.08391496511, 15276.618084547892, 15279.618009549768, 15282.923585382085, 11921.58274460394, 13828.377723002464, 13870.791938120936, 14811.629709257273, 15360.539183824476, 909.3498148823589, 915.8809354783886, 919.4896832258094, 923.1737594852602, 923.9769005774842, 919.645936314517, 923.4044041593169, 923.6859467780953, 924.2513564013171, 927.8654595083709, 941.3598752848061, 945.4043952310035, 946.9961840449112, 949.4255975135047, 952.1478276942132, 942.1614762861059, 943.4245110482634, 943.9244860411151, 945.1493655709847, 948.9240860731124, 700.6216643012767, 704.232386698501, 704.9118860142482, 705.3158436316764, 706.2231545300128, 14772.48296309624, 14786.704265914621, 14808.111513309235, 14812.851871481254, 14849.925750371287, 11395.917387848223, 12405.069619778462, 12947.0, 13031.804522932163, 13245.0, 11990.640280791591, 12235.510579576807, 12244.877551224552, 12349.753004939941, 12479.875201247962, 4191.3293872980485, 4436.268015777425, 4478.820847166109, 4541.591256786872, 4565.315202719586, 80034.5998270005, 80478.19521804707, 80577.59711201406, 80687.0, 81387.0, 10308.742281442874, 10490.632827850988, 10491.580336786596, 10507.737306567396, 10798.244122911477, 19745.40763777089, 19959.60080798376, 19967.900160499106, 19975.101120449614, 20325.898370508054, 7699.961500192575, 7768.2620151086685, 7805.0, 7971.60141992898, 7975.8404831903035, 4381.123775244963, 4446.0, 4455.064436468378, 4469.44131983502, 4680.485146633868, 3266.6733326667145, 3422.8973130806116, 3424.9828750856086, 3426.3832510148413, 3432.1934345428804, 564.520157865819, 639.280809089774, 649.3474058571106, 651.3453978751324, 665.0755449924609, 534.1160379571814, 545.4654438650145, 610.7953835465169, 648.8029585414902, 672.67711498481, 8623.137686231452, 8976.326775491863, 9037.68368107113, 9133.45199288045, 9211.568938193292, 551.6855392426279, 581.8080033588906, 590.8818236352747, 654.2312782480566, 659.980200593983, 3609.0, 3912.4326972589383, 4015.397690346413, 4088.3867419887256, 4131.26486875513, 77563.61218193985, 77587.0, 78259.0, 79799.803002955, 84580.57709711535, 73374.2662573378, 75253.62373188099, 75361.62319188369, 75872.0, 77361.22638773653, 68809.0, 69495.65252173707, 69802.95295570671, 70006.64996674984, 70444.23889402807, 7212.855742885008, 8251.628676709683, 8317.41778075529, 8342.957130358705, 8631.654733810577, 42171.0, 42407.0, 42854.0, 43295.783521082194, 43552.0, 45324.77337613291, 59597.404025959186, 59626.10560841678, 60218.69890650519, 60470.69764651325, 11445.313281203154, 11564.0, 12404.56584019555, 12516.374181291081, 12686.048546358876, 14123.364448600043, 14262.358193881508, 14420.927895360455, 14425.206613635932, 14657.340419681354, 13057.608271751482, 13121.359830021247, 13191.538296159588, 13601.319934003459, 13788.172709637454, 13088.476460941456, 13165.407556659782, 13177.0, 13187.142835715647, 13250.403731831899, 12730.554430594884, 12730.618081457571, 12876.29070691047, 13010.219386836441, 13132.277724725232, 10927.096460502014, 11037.448127593749, 11080.279781814157, 11332.830007549728, 11440.94279528597, 10590.523426445674, 11126.16418290973, 11359.432028398713, 11369.374684392438, 11393.373364465035], "value": 7160.948245487688, "unit": "score"}], "name": "dromaeo_css", "value": 7226.275364355497, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 111.0, 110.0, 112.0, 112.0, 113.0, 157.0, 164.0, 137.0], "value": 123.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 126.0, 117.0, 168.0, 119.0, 117.0, 137.0, 118.0, 154.0, 117.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 181.0, 182.0, 183.0, 184.0, 182.0, 177.0, 181.0, 180.0], "value": 181.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 57.0, 117.0, 49.0, 69.0, 88.0, 89.0, 97.0, 55.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 77.0, 70.0, 57.0, 89.0, 117.0, 102.0, 99.0, 131.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 83.0, 96.0, 131.0, 87.0, 84.0, 86.0, 83.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 151.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 92.0, 94.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 84.0, 84.0, 84.0, 83.0, 84.0, 98.0, 138.0, 138.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 53.0, 55.0, 55.0, 53.0, 53.0, 54.0, 56.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 72.0, 75.0, 74.0, 96.0, 100.0, 101.0, 72.0, 70.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 86.0, 102.0, 87.0, 86.0, 86.0, 144.0, 85.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 142.0, 137.0, 138.0, 134.0, 126.0, 175.0, 168.0, 150.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 165.0, 166.0, 168.0, 165.0, 165.0, 164.0, 160.0, 163.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 65.0, 64.0, 66.0, 66.0, 64.0, 66.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1500.1000000000001, "alertThreshold": 2.0}]} 19:15:28 INFO - Return code: 0 19:15:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:15:28 ERROR - # TBPL SUCCESS # 19:15:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:15:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:15:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:15:28 INFO - 2016-05-31 19:15:28.832 screenresolution[1684:9898] starting screenresolution argv=screenresolution get 19:15:28 INFO - 2016-05-31 19:15:28.835 screenresolution[1684:9898] Display 0: 1600x1200x32@60 19:15:28 INFO - 2016-05-31 19:15:28.842 screenresolution[1685:9901] starting screenresolution argv=screenresolution list 19:15:28 INFO - Available Modes on Display 0 19:15:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:15:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:15:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:15:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:15:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:15:29 INFO - Intel Iris: 19:15:29 INFO - Chipset Model: Intel Iris 19:15:29 INFO - Type: GPU 19:15:29 INFO - Bus: Built-In 19:15:29 INFO - VRAM (Dynamic, Max): 1536 MB 19:15:29 INFO - Vendor: Intel (0x8086) 19:15:29 INFO - Device ID: 0x0a2e 19:15:29 INFO - Revision ID: 0x0009 19:15:29 INFO - Displays: 19:15:29 INFO - Display: 19:15:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:15:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:15:29 INFO - Display Serial Number: 1600x1200 60 19:15:29 INFO - Main Display: Yes 19:15:29 INFO - Mirror: Off 19:15:29 INFO - Online: Yes 19:15:29 INFO - Rotation: Supported 19:15:29 INFO - Return code: 0 19:15:29 INFO - Running post-action listener: _resource_record_post_action 19:15:29 INFO - ##### 19:15:29 INFO - ##### Finished run-tests step (success) 19:15:29 INFO - ##### 19:15:29 INFO - Running post-run listener: _resource_record_post_run 19:15:29 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32230912; Write bytes: 695700992; Read time: 312; Write time: 2109 19:15:29 INFO - TinderboxPrint: CPU usage
53.8% 19:15:29 INFO - TinderboxPrint: I/O read bytes / time
32,230,912 / 312 19:15:29 INFO - TinderboxPrint: I/O write bytes / time
695,700,992 / 2,109 19:15:29 INFO - TinderboxPrint: CPU idle
2,239.4 (46.2%) 19:15:29 INFO - TinderboxPrint: CPU system
971.9 (20.1%) 19:15:29 INFO - TinderboxPrint: CPU user
1,634.4 (33.7%) 19:15:29 INFO - TinderboxPrint: Swap in / out
427,642,880 / 0 19:15:29 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378475008; Write bytes: 371934720; Read time: 28122; Write time: 678 19:15:29 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31309312; Write bytes: 294467584; Read time: 298; Write time: 1346 19:15:29 INFO - Running post-run listener: _upload_blobber_files 19:15:29 INFO - Blob upload gear active. 19:15:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:15:29 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 19:15:29 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'] 19:15:29 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 19:15:29 INFO - (blobuploader) - INFO - Open directory for files ... 19:15:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:15:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:15:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:15:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:15:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:15:31 INFO - (blobuploader) - INFO - Done attempting. 19:15:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:15:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:15:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:15:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:15:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:15:32 INFO - (blobuploader) - INFO - Done attempting. 19:15:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:15:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:15:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:15:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:15:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:15:35 INFO - (blobuploader) - INFO - Done attempting. 19:15:35 INFO - (blobuploader) - INFO - Iteration through files over. 19:15:35 INFO - Return code: 0 19:15:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:15:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:15:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fca1f6400b2c66dff6d0c245cbde4bc034f5b381dbdb0f37cb8605127a86f2d91dac55c43158a63ebcd4b5a0543a4d3627150d57c852ec009e884bb54a94b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/627a3ac8b24703351081ff6ee247e7c9cf5701081f94452b5b819170223c06fa781be3484f375e7ef36ccc255aaf9bd91892bcb073c5ca3ede605782b23f805c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11a90c18db3beb7d50dc7e47d6d5a987f6d36f07283957981f91d235286fc766fc6c7216056eb5b11c3e2c9b8700a46048828168dc287011f34dfd9f93feb732"} 19:15:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:15:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:15:35 INFO - Contents: 19:15:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fca1f6400b2c66dff6d0c245cbde4bc034f5b381dbdb0f37cb8605127a86f2d91dac55c43158a63ebcd4b5a0543a4d3627150d57c852ec009e884bb54a94b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/627a3ac8b24703351081ff6ee247e7c9cf5701081f94452b5b819170223c06fa781be3484f375e7ef36ccc255aaf9bd91892bcb073c5ca3ede605782b23f805c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11a90c18db3beb7d50dc7e47d6d5a987f6d36f07283957981f91d235286fc766fc6c7216056eb5b11c3e2c9b8700a46048828168dc287011f34dfd9f93feb732"} 19:15:35 INFO - Running post-run listener: copy_logs_to_upload_dir 19:15:35 INFO - Copying logs to upload dir... 19:15:35 INFO - mkdir: /builds/slave/test/build/upload/logs 19:15:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.696980 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-05-31 19:15:35.256122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 19:15:35.260224) ========= 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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fca1f6400b2c66dff6d0c245cbde4bc034f5b381dbdb0f37cb8605127a86f2d91dac55c43158a63ebcd4b5a0543a4d3627150d57c852ec009e884bb54a94b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/627a3ac8b24703351081ff6ee247e7c9cf5701081f94452b5b819170223c06fa781be3484f375e7ef36ccc255aaf9bd91892bcb073c5ca3ede605782b23f805c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11a90c18db3beb7d50dc7e47d6d5a987f6d36f07283957981f91d235286fc766fc6c7216056eb5b11c3e2c9b8700a46048828168dc287011f34dfd9f93feb732"} build_url:https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/A4IrTpsrTmGxqTc6yoh4Yg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fca1f6400b2c66dff6d0c245cbde4bc034f5b381dbdb0f37cb8605127a86f2d91dac55c43158a63ebcd4b5a0543a4d3627150d57c852ec009e884bb54a94b53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/627a3ac8b24703351081ff6ee247e7c9cf5701081f94452b5b819170223c06fa781be3484f375e7ef36ccc255aaf9bd91892bcb073c5ca3ede605782b23f805c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11a90c18db3beb7d50dc7e47d6d5a987f6d36f07283957981f91d235286fc766fc6c7216056eb5b11c3e2c9b8700a46048828168dc287011f34dfd9f93feb732"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 19:15:35.291453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 19:15:35.291796) ========= 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.SmAJtC3iNA/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.JqkJD5ik0s/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.005355 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 19:15:35.341149) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 19:15:35.341480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 19:15:35.341882) ========= ========= Total master_lag: 3.18 =========