builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0239 starttime: 1473237335.7 results: success (0) buildid: 20160906234616 builduid: 0d97f8796d6d42179756f4011e6e5439 revision: d5338a598b481b9c4653c8b64ab70db66b9362b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:35.705141) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:35.706066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:35.706693) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:35.731326) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:35.732023) ========= 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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-09-07 01:35:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.2M=0.001s 2016-09-07 01:35:36 (19.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.419957 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:36.179853) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:36.180579) ========= 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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.026961 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:36.233004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 01:35:36.233716) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d5338a598b481b9c4653c8b64ab70db66b9362b4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d5338a598b481b9c4653c8b64ab70db66b9362b4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TEVcK1Trdc/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.pAhrhFZU3m/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-09-07 01:35:36,319 truncating revision to first 12 chars 2016-09-07 01:35:36,319 Setting DEBUG logging. 2016-09-07 01:35:36,320 attempt 1/10 2016-09-07 01:35:36,320 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d5338a598b48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 01:35:37,394 unpacking tar archive at: autoland-d5338a598b48/testing/mozharness/ program finished with exit code 0 elapsedTime=1.351911 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 01:35:37.610571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:37.610955) ========= script_repo_revision: d5338a598b481b9c4653c8b64ab70db66b9362b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:37.611441) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:37.611746) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 01:35:37.630839) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-07 01:35:37.631183) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TEVcK1Trdc/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.pAhrhFZU3m/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:35:37 INFO - MultiFileLogger online at 20160907 01:35:37 in /builds/slave/test 01:35:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 01:35:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:35:37 INFO - {'append_to_log': False, 01:35:37 INFO - 'base_work_dir': '/builds/slave/test', 01:35:37 INFO - 'blob_upload_branch': 'Autoland', 01:35:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:35:37 INFO - 'branch': 'Autoland', 01:35:37 INFO - 'buildbot_json_path': 'buildprops.json', 01:35:37 INFO - 'config_files': ('talos/mac_config.py',), 01:35:37 INFO - 'default_actions': ('clobber', 01:35:37 INFO - 'read-buildbot-config', 01:35:37 INFO - 'download-and-extract', 01:35:37 INFO - 'populate-webroot', 01:35:37 INFO - 'create-virtualenv', 01:35:37 INFO - 'install', 01:35:37 INFO - 'run-tests'), 01:35:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:35:37 INFO - 'download_minidump_stackwalk': True, 01:35:37 INFO - 'download_symbols': 'ondemand', 01:35:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:35:37 INFO - 'tooltool.py': '/tools/tooltool.py', 01:35:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:35:37 INFO - '/tools/misc-python/virtualenv.py')}, 01:35:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:35:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:35:37 INFO - 'installer_path': 'installer.exe', 01:35:37 INFO - 'log_level': 'info', 01:35:37 INFO - 'log_name': 'talos', 01:35:37 INFO - 'log_to_console': True, 01:35:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:35:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:35:37 INFO - 'opt_config_files': (), 01:35:37 INFO - 'pip_index': False, 01:35:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:37 INFO - 'cmd': ('bash', 01:35:37 INFO - '-c', 01:35:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:37 INFO - 'enabled': True, 01:35:37 INFO - 'halt_on_failure': False, 01:35:37 INFO - 'name': 'check_screen_resolution'},), 01:35:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:37 INFO - 'cmd': ('bash', 01:35:37 INFO - '-c', 01:35:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:37 INFO - 'enabled': True, 01:35:37 INFO - 'halt_on_failure': False, 01:35:37 INFO - 'name': 'check_screen_resolution'},), 01:35:37 INFO - 'run_cmd_checks_enabled': True, 01:35:37 INFO - 'sps_profile': False, 01:35:37 INFO - 'sps_profile_interval': 0, 01:35:37 INFO - 'suite': 'dromaeojs', 01:35:37 INFO - 'system_bits': '32', 01:35:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:35:37 INFO - 'title': 't-yosemite-r7-0239', 01:35:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:35:37 INFO - 'use_talos_json': True, 01:35:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:35:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:35:37 INFO - 'work_dir': 'build'} 01:35:37 INFO - [mozharness: 2016-09-07 08:35:37.830025Z] Running clobber step. 01:35:37 INFO - Running pre-action listener: _resource_record_pre_action 01:35:37 INFO - Running main action method: clobber 01:35:37 INFO - rmtree: /builds/slave/test/build 01:35:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:35:39 INFO - Running post-action listener: _resource_record_post_action 01:35:39 INFO - [mozharness: 2016-09-07 08:35:39.975601Z] Finished clobber step (success) 01:35:39 INFO - [mozharness: 2016-09-07 08:35:39.975721Z] Running read-buildbot-config step. 01:35:39 INFO - Running pre-action listener: _resource_record_pre_action 01:35:39 INFO - Running main action method: read_buildbot_config 01:35:39 INFO - Using buildbot properties: 01:35:39 INFO - { 01:35:39 INFO - "project": "", 01:35:39 INFO - "product": "firefox", 01:35:39 INFO - "script_repo_revision": "production", 01:35:39 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 01:35:39 INFO - "repository": "", 01:35:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 01:35:39 INFO - "buildid": "20160906234616", 01:35:39 INFO - "pgo_build": "False", 01:35:39 INFO - "basedir": "/builds/slave/test", 01:35:39 INFO - "buildnumber": 160, 01:35:39 INFO - "slavename": "t-yosemite-r7-0239", 01:35:39 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:35:39 INFO - "platform": "yosemite_r7", 01:35:39 INFO - "scheduler": "tests-autoland-macosx64-talos", 01:35:39 INFO - "branch": "autoland", 01:35:39 INFO - "repo_path": "integration/autoland", 01:35:39 INFO - "revision": "d5338a598b481b9c4653c8b64ab70db66b9362b4", 01:35:39 INFO - "stage_platform": "macosx64", 01:35:39 INFO - "builduid": "0d97f8796d6d42179756f4011e6e5439", 01:35:39 INFO - "slavebuilddir": "test" 01:35:39 INFO - } 01:35:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 01:35:39 INFO - Running post-action listener: _resource_record_post_action 01:35:39 INFO - [mozharness: 2016-09-07 08:35:39.978615Z] Finished read-buildbot-config step (success) 01:35:39 INFO - [mozharness: 2016-09-07 08:35:39.978719Z] Running download-and-extract step. 01:35:39 INFO - Running pre-action listener: _resource_record_pre_action 01:35:39 INFO - Running main action method: download_and_extract 01:35:39 INFO - mkdir: /builds/slave/test/build/tests 01:35:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:39 INFO - trying https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 01:35:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:35:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 01:35:41 INFO - Downloaded 1199 bytes. 01:35:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:35:41 INFO - Using the following test package requirements: 01:35:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:35:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 01:35:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 01:35:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'jsshell-mac.zip'], 01:35:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 01:35:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:35:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 01:35:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 01:35:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 01:35:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:35:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 01:35:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:35:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:35:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:35:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:35:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:35:49 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:35:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 01:35:56 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:35:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:57 INFO - trying https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:35:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 01:35:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 01:36:00 INFO - Downloaded 111511707 bytes. 01:36:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:36:00 INFO - mkdir: /builds/slave/test/properties 01:36:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:36:00 INFO - Writing to file /builds/slave/test/properties/build_url 01:36:00 INFO - Contents: 01:36:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:36:01 INFO - Running post-action listener: _resource_record_post_action 01:36:01 INFO - Running post-action listener: set_extra_try_arguments 01:36:01 INFO - [mozharness: 2016-09-07 08:36:01.017342Z] Finished download-and-extract step (success) 01:36:01 INFO - [mozharness: 2016-09-07 08:36:01.017463Z] Running populate-webroot step. 01:36:01 INFO - Running pre-action listener: _resource_record_pre_action 01:36:01 INFO - Running main action method: populate_webroot 01:36:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:36:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:36:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:36:01 INFO - '--tppagecycles', 01:36:01 INFO - '7'], 01:36:01 INFO - 'tests': ['tsvgm']}}, 01:36:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:36:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:36:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:36:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:36:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['damp', 'tps']}, 01:36:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'tests': ['damp', 'tps']}, 01:36:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['dromaeo_dom']}, 01:36:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:36:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['basic_compositor_video']}, 01:36:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:36:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['a11yr', 01:36:01 INFO - 'ts_paint', 01:36:01 INFO - 'tpaint', 01:36:01 INFO - 'sessionrestore', 01:36:01 INFO - 'sessionrestore_no_auto_restore', 01:36:01 INFO - 'tabpaint']}, 01:36:01 INFO - 'other-e10s': {'tests': ['a11yr', 01:36:01 INFO - 'ts_paint', 01:36:01 INFO - 'tpaint', 01:36:01 INFO - 'sessionrestore', 01:36:01 INFO - 'sessionrestore_no_auto_restore', 01:36:01 INFO - 'tabpaint']}, 01:36:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:36:01 INFO - 'ts_paint', 01:36:01 INFO - 'tpaint', 01:36:01 INFO - 'sessionrestore', 01:36:01 INFO - 'sessionrestore_no_auto_restore', 01:36:01 INFO - 'tabpaint']}, 01:36:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:36:01 INFO - 'ts_paint', 01:36:01 INFO - 'tpaint', 01:36:01 INFO - 'sessionrestore', 01:36:01 INFO - 'sessionrestore_no_auto_restore', 01:36:01 INFO - 'tabpaint']}, 01:36:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['a11yr', 01:36:01 INFO - 'ts_paint', 01:36:01 INFO - 'tpaint', 01:36:01 INFO - 'sessionrestore', 01:36:01 INFO - 'sessionrestore_no_auto_restore', 01:36:01 INFO - 'tabpaint']}, 01:36:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['a11yr', 01:36:01 INFO - 'ts_paint', 01:36:01 INFO - 'tpaint', 01:36:01 INFO - 'sessionrestore', 01:36:01 INFO - 'sessionrestore_no_auto_restore', 01:36:01 INFO - 'tabpaint']}, 01:36:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['tsvgx', 01:36:01 INFO - 'tsvgr_opacity', 01:36:01 INFO - 'tart', 01:36:01 INFO - 'tscrollx', 01:36:01 INFO - 'cart']}, 01:36:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:36:01 INFO - 'tsvgr_opacity', 01:36:01 INFO - 'tart', 01:36:01 INFO - 'tscrollx', 01:36:01 INFO - 'cart']}, 01:36:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'talos_options': ['--disable-e10s'], 01:36:01 INFO - 'tests': ['tp5o']}, 01:36:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'tests': ['tp5o']}, 01:36:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'talos_options': ['--disable-e10s', 01:36:01 INFO - '--xperf_path', 01:36:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:01 INFO - 'tests': ['tp5n']}, 01:36:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:36:01 INFO - 'talos_options': ['--xperf_path', 01:36:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:01 INFO - 'tests': ['tp5n']}}, 01:36:01 INFO - 'talos.zip': {'path': '', 01:36:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:36:01 INFO - Running post-action listener: _resource_record_post_action 01:36:01 INFO - [mozharness: 2016-09-07 08:36:01.036671Z] Finished populate-webroot step (success) 01:36:01 INFO - [mozharness: 2016-09-07 08:36:01.036785Z] Running create-virtualenv step. 01:36:01 INFO - Running pre-action listener: _resource_record_pre_action 01:36:01 INFO - Running main action method: create_virtualenv 01:36:01 INFO - Creating virtualenv /builds/slave/test/build/venv 01:36:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:36:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:36:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:36:01 INFO - Using real prefix '/tools/python27' 01:36:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:36:01 INFO - Installing distribute.............................................................................................................................................................................................done. 01:36:04 INFO - Installing pip.................done. 01:36:04 INFO - Return code: 0 01:36:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:04 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:36:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:36:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:04 INFO - 'HOME': '/Users/cltbld', 01:36:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:04 INFO - 'LOGNAME': 'cltbld', 01:36:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:04 INFO - 'MOZ_NO_REMOTE': '1', 01:36:04 INFO - 'NO_EM_RESTART': '1', 01:36:04 INFO - 'PAGER': '/bin/cat', 01:36:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:04 INFO - 'PWD': '/builds/slave/test', 01:36:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:04 INFO - 'SHELL': '/bin/bash', 01:36:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:04 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:04 INFO - 'USER': 'cltbld', 01:36:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:04 INFO - 'XPC_FLAGS': '0x0', 01:36:04 INFO - 'XPC_SERVICE_NAME': '0', 01:36:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:36:04 INFO - Cleaning up... 01:36:04 INFO - Return code: 0 01:36:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:04 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:36:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:36:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:04 INFO - 'HOME': '/Users/cltbld', 01:36:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:04 INFO - 'LOGNAME': 'cltbld', 01:36:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:04 INFO - 'MOZ_NO_REMOTE': '1', 01:36:04 INFO - 'NO_EM_RESTART': '1', 01:36:05 INFO - 'PAGER': '/bin/cat', 01:36:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:05 INFO - 'PWD': '/builds/slave/test', 01:36:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:05 INFO - 'SHELL': '/bin/bash', 01:36:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:05 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:05 INFO - 'USER': 'cltbld', 01:36:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:05 INFO - 'XPC_FLAGS': '0x0', 01:36:05 INFO - 'XPC_SERVICE_NAME': '0', 01:36:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:05 INFO - Downloading/unpacking psutil>=3.1.1 01:36:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:36:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:08 INFO - Installing collected packages: psutil 01:36:08 INFO - Running setup.py install for psutil 01:36:08 INFO - building 'psutil._psutil_osx' extension 01:36:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:36:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:36:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:36:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:36:09 INFO - building 'psutil._psutil_posix' extension 01:36:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:36:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:36:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:36:09 INFO - ^ 01:36:09 INFO - 1 warning generated. 01:36:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:36:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:09 INFO - Successfully installed psutil 01:36:09 INFO - Cleaning up... 01:36:09 INFO - Return code: 0 01:36:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:36:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:09 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:09 INFO - 'HOME': '/Users/cltbld', 01:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:09 INFO - 'LOGNAME': 'cltbld', 01:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:09 INFO - 'MOZ_NO_REMOTE': '1', 01:36:09 INFO - 'NO_EM_RESTART': '1', 01:36:09 INFO - 'PAGER': '/bin/cat', 01:36:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:09 INFO - 'PWD': '/builds/slave/test', 01:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:09 INFO - 'SHELL': '/bin/bash', 01:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:09 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:09 INFO - 'USER': 'cltbld', 01:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:09 INFO - 'XPC_FLAGS': '0x0', 01:36:09 INFO - 'XPC_SERVICE_NAME': '0', 01:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:36:12 INFO - Installing collected packages: mozsystemmonitor 01:36:12 INFO - Running setup.py install for mozsystemmonitor 01:36:12 INFO - Successfully installed mozsystemmonitor 01:36:12 INFO - Cleaning up... 01:36:12 INFO - Return code: 0 01:36:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:36: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 jsonschema==2.5.1 01:36:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:12 INFO - 'HOME': '/Users/cltbld', 01:36:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:12 INFO - 'LOGNAME': 'cltbld', 01:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:12 INFO - 'MOZ_NO_REMOTE': '1', 01:36:12 INFO - 'NO_EM_RESTART': '1', 01:36:12 INFO - 'PAGER': '/bin/cat', 01:36:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:12 INFO - 'PWD': '/builds/slave/test', 01:36:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:12 INFO - 'SHELL': '/bin/bash', 01:36:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:12 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:12 INFO - 'USER': 'cltbld', 01:36:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:12 INFO - 'XPC_FLAGS': '0x0', 01:36:12 INFO - 'XPC_SERVICE_NAME': '0', 01:36:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:13 INFO - Downloading/unpacking jsonschema==2.5.1 01:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:36:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36:15 INFO - Downloading functools32-3.2.3-2.tar.gz 01:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:36:16 INFO - warning: no files found matching '*.txt' 01:36:16 INFO - no previously-included directories found matching 'build' 01:36:16 INFO - no previously-included directories found matching 'dist' 01:36:16 INFO - no previously-included directories found matching '.git*' 01:36:16 INFO - Installing collected packages: jsonschema, functools32 01:36:16 INFO - Running setup.py install for functools32 01:36:16 INFO - warning: no files found matching '*.txt' 01:36:16 INFO - no previously-included directories found matching 'build' 01:36:16 INFO - no previously-included directories found matching 'dist' 01:36:16 INFO - no previously-included directories found matching '.git*' 01:36:16 INFO - Successfully installed jsonschema functools32 01:36:16 INFO - Cleaning up... 01:36:16 INFO - Return code: 0 01:36:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:16 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:16 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:36:16 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 functools32==3.2.3-2 01:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:16 INFO - 'HOME': '/Users/cltbld', 01:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:16 INFO - 'LOGNAME': 'cltbld', 01:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:16 INFO - 'MOZ_NO_REMOTE': '1', 01:36:16 INFO - 'NO_EM_RESTART': '1', 01:36:16 INFO - 'PAGER': '/bin/cat', 01:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:16 INFO - 'PWD': '/builds/slave/test', 01:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:16 INFO - 'SHELL': '/bin/bash', 01:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:16 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:16 INFO - 'USER': 'cltbld', 01:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:16 INFO - 'XPC_FLAGS': '0x0', 01:36:16 INFO - 'XPC_SERVICE_NAME': '0', 01:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:36:16 INFO - Cleaning up... 01:36:16 INFO - Return code: 0 01:36:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:16 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:36:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:16 INFO - 'HOME': '/Users/cltbld', 01:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:16 INFO - 'LOGNAME': 'cltbld', 01:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:16 INFO - 'MOZ_NO_REMOTE': '1', 01:36:16 INFO - 'NO_EM_RESTART': '1', 01:36:16 INFO - 'PAGER': '/bin/cat', 01:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:16 INFO - 'PWD': '/builds/slave/test', 01:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:16 INFO - 'SHELL': '/bin/bash', 01:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:16 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:16 INFO - 'USER': 'cltbld', 01:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:16 INFO - 'XPC_FLAGS': '0x0', 01:36:16 INFO - 'XPC_SERVICE_NAME': '0', 01:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:16 INFO - Downloading/unpacking blobuploader==1.2.4 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:19 INFO - Downloading blobuploader-1.2.4.tar.gz 01:36:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:36:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:36:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:20 INFO - Downloading docopt-0.6.1.tar.gz 01:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:36:20 INFO - Installing collected packages: blobuploader, requests, docopt 01:36:20 INFO - Running setup.py install for blobuploader 01:36:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:36:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:36:20 INFO - Running setup.py install for requests 01:36:21 INFO - Running setup.py install for docopt 01:36:21 INFO - Successfully installed blobuploader requests docopt 01:36:21 INFO - Cleaning up... 01:36:21 INFO - Return code: 0 01:36:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:21 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:36:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:36:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:21 INFO - 'HOME': '/Users/cltbld', 01:36:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:21 INFO - 'LOGNAME': 'cltbld', 01:36:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:21 INFO - 'MOZ_NO_REMOTE': '1', 01:36:21 INFO - 'NO_EM_RESTART': '1', 01:36:21 INFO - 'PAGER': '/bin/cat', 01:36:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:21 INFO - 'PWD': '/builds/slave/test', 01:36:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:21 INFO - 'SHELL': '/bin/bash', 01:36:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:21 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:21 INFO - 'USER': 'cltbld', 01:36:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:21 INFO - 'XPC_FLAGS': '0x0', 01:36:21 INFO - 'XPC_SERVICE_NAME': '0', 01:36:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-wnJKU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-NvjQtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-id0h83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-O7rsOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Tefq5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-twhFK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-27MqTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-U5bm5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ePuoLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-fcGLjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-TArd84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-F8Sc2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:23 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-X_rB87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:23 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-0sNCxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:23 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-oPNdeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:23 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-zpJbSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:23 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-1eXiiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:36:23 INFO - Running setup.py install for manifestparser 01:36:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:36:23 INFO - Running setup.py install for mozcrash 01:36:23 INFO - Running setup.py install for mozdebug 01:36:23 INFO - Running setup.py install for mozdevice 01:36:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Running setup.py install for mozfile 01:36:24 INFO - Running setup.py install for mozhttpd 01:36:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Running setup.py install for mozinfo 01:36:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Running setup.py install for mozInstall 01:36:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:36:24 INFO - Running setup.py install for mozleak 01:36:24 INFO - Running setup.py install for mozlog 01:36:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:36:25 INFO - Running setup.py install for moznetwork 01:36:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:36:25 INFO - Running setup.py install for mozprocess 01:36:25 INFO - Running setup.py install for mozprofile 01:36:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:36:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:36:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:36:25 INFO - Running setup.py install for mozrunner 01:36:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:36:25 INFO - Running setup.py install for mozscreenshot 01:36:25 INFO - Running setup.py install for moztest 01:36:25 INFO - Running setup.py install for mozversion 01:36:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:36:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:36:26 INFO - Cleaning up... 01:36:26 INFO - Return code: 0 01:36:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:26 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:36:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:26 INFO - 'HOME': '/Users/cltbld', 01:36:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:26 INFO - 'LOGNAME': 'cltbld', 01:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:26 INFO - 'MOZ_NO_REMOTE': '1', 01:36:26 INFO - 'NO_EM_RESTART': '1', 01:36:26 INFO - 'PAGER': '/bin/cat', 01:36:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:26 INFO - 'PWD': '/builds/slave/test', 01:36:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:26 INFO - 'SHELL': '/bin/bash', 01:36:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:26 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:26 INFO - 'USER': 'cltbld', 01:36:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:26 INFO - 'XPC_FLAGS': '0x0', 01:36:26 INFO - 'XPC_SERVICE_NAME': '0', 01:36:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:26 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-CjpF3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:26 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-j6rzuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:26 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Xf9cdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:26 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-SdAdkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:26 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-y3VHwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:26 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-dab826-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-4Ha9Qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-oaTuNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-WFABaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-RVqhga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-fHReZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ihCtxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-sJMf79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-rWZtUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-KPh9ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-sqvtuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-uxxhkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:30 INFO - Downloading blessings-1.6.tar.gz 01:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:36:30 INFO - Installing collected packages: blessings 01:36:30 INFO - Running setup.py install for blessings 01:36:31 INFO - Successfully installed blessings 01:36:31 INFO - Cleaning up... 01:36:31 INFO - Return code: 0 01:36:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:36:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:31 INFO - Reading from file tmpfile_stdout 01:36:31 INFO - Current package versions: 01:36:31 INFO - blessings == 1.6 01:36:31 INFO - blobuploader == 1.2.4 01:36:31 INFO - docopt == 0.6.1 01:36:31 INFO - functools32 == 3.2.3-2 01:36:31 INFO - jsonschema == 2.5.1 01:36:31 INFO - manifestparser == 1.1 01:36:31 INFO - mozInstall == 1.12 01:36:31 INFO - mozcrash == 0.17 01:36:31 INFO - mozdebug == 0.1 01:36:31 INFO - mozdevice == 0.48 01:36:31 INFO - mozfile == 1.2 01:36:31 INFO - mozhttpd == 0.7 01:36:31 INFO - mozinfo == 0.9 01:36:31 INFO - mozleak == 0.1 01:36:31 INFO - mozlog == 3.3 01:36:31 INFO - moznetwork == 0.27 01:36:31 INFO - mozprocess == 0.23 01:36:31 INFO - mozprofile == 0.28 01:36:31 INFO - mozrunner == 6.12 01:36:31 INFO - mozscreenshot == 0.1 01:36:31 INFO - mozsystemmonitor == 0.3 01:36:31 INFO - moztest == 0.7 01:36:31 INFO - mozversion == 1.4 01:36:31 INFO - psutil == 3.1.1 01:36:31 INFO - requests == 1.2.3 01:36:31 INFO - wsgiref == 0.1.2 01:36:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:31 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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:36:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:31 INFO - 'HOME': '/Users/cltbld', 01:36:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:31 INFO - 'LOGNAME': 'cltbld', 01:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:31 INFO - 'MOZ_NO_REMOTE': '1', 01:36:31 INFO - 'NO_EM_RESTART': '1', 01:36:31 INFO - 'PAGER': '/bin/cat', 01:36:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:31 INFO - 'PWD': '/builds/slave/test', 01:36:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:31 INFO - 'SHELL': '/bin/bash', 01:36:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:31 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:31 INFO - 'USER': 'cltbld', 01:36:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:31 INFO - 'XPC_FLAGS': '0x0', 01:36:31 INFO - 'XPC_SERVICE_NAME': '0', 01:36:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:36:31 INFO - Cleaning up... 01:36:31 INFO - Return code: 0 01:36:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x106cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024cdd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, '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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:36:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:36:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:31 INFO - 'HOME': '/Users/cltbld', 01:36:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:31 INFO - 'LOGNAME': 'cltbld', 01:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:31 INFO - 'MOZ_NO_REMOTE': '1', 01:36:31 INFO - 'NO_EM_RESTART': '1', 01:36:31 INFO - 'PAGER': '/bin/cat', 01:36:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:31 INFO - 'PWD': '/builds/slave/test', 01:36:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:31 INFO - 'SHELL': '/bin/bash', 01:36:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:36:31 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:36:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:31 INFO - 'USER': 'cltbld', 01:36:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:31 INFO - 'XPC_FLAGS': '0x0', 01:36:31 INFO - 'XPC_SERVICE_NAME': '0', 01:36:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:36:31 INFO - Cleaning up... 01:36:31 INFO - Return code: 0 01:36:31 INFO - Running post-action listener: _resource_record_post_action 01:36:31 INFO - Running post-action listener: _start_resource_monitoring 01:36:31 INFO - Starting resource monitoring. 01:36:31 INFO - [mozharness: 2016-09-07 08:36:31.977051Z] Finished create-virtualenv step (success) 01:36:31 INFO - [mozharness: 2016-09-07 08:36:31.978047Z] Running install step. 01:36:31 INFO - Running pre-action listener: _resource_record_pre_action 01:36:31 INFO - Running main action method: install 01:36:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:32 INFO - Reading from file tmpfile_stdout 01:36:32 INFO - Detecting whether we're running mozinstall >=1.0... 01:36:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:36:32 INFO - Reading from file tmpfile_stdout 01:36:32 INFO - Output received: 01:36:32 INFO - Usage: mozinstall [options] installer 01:36:32 INFO - Options: 01:36:32 INFO - -h, --help show this help message and exit 01:36:32 INFO - -d DEST, --destination=DEST 01:36:32 INFO - Directory to install application into. [default: 01:36:32 INFO - "/builds/slave/test"] 01:36:32 INFO - --app=APP Application being installed. [default: firefox] 01:36:32 INFO - mkdir: /builds/slave/test/build/application 01:36:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:37:05 INFO - Reading from file tmpfile_stdout 01:37:05 INFO - Output received: 01:37:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:37:05 INFO - Running post-action listener: _resource_record_post_action 01:37:05 INFO - [mozharness: 2016-09-07 08:37:05.863324Z] Finished install step (success) 01:37:05 INFO - [mozharness: 2016-09-07 08:37:05.863480Z] Running run-tests step. 01:37:05 INFO - Running pre-action listener: _resource_record_pre_action 01:37:05 INFO - Running main action method: run_tests 01:37:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:37:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:37:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:37:05 INFO - 2016-09-07 01:37:05.878 screenresolution[1654:7404] starting screenresolution argv=screenresolution get 01:37:05 INFO - 2016-09-07 01:37:05.880 screenresolution[1654:7404] Display 0: 1600x1200x32@60 01:37:05 INFO - 2016-09-07 01:37:05.886 screenresolution[1655:7406] starting screenresolution argv=screenresolution list 01:37:05 INFO - Available Modes on Display 0 01:37:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:37:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:37:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:37:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:37:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:37:06 INFO - Intel Iris: 01:37:06 INFO - Chipset Model: Intel Iris 01:37:06 INFO - Type: GPU 01:37:06 INFO - Bus: Built-In 01:37:06 INFO - VRAM (Dynamic, Max): 1536 MB 01:37:06 INFO - Vendor: Intel (0x8086) 01:37:06 INFO - Device ID: 0x0a2e 01:37:06 INFO - Revision ID: 0x0009 01:37:06 INFO - Displays: 01:37:06 INFO - Display: 01:37:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:37:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:37:06 INFO - Display Serial Number: 1600x1200 60 01:37:06 INFO - Main Display: Yes 01:37:06 INFO - Mirror: Off 01:37:06 INFO - Online: Yes 01:37:06 INFO - Rotation: Supported 01:37:06 INFO - Return code: 0 01:37:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:37:06 INFO - Python 2.7.3 01:37:06 INFO - Return code: 0 01:37:06 INFO - grabbing minidump binary from tooltool 01:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:06 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 0x106c3ad30>, '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 0x10699fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:37:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:37:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:37:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:37:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:37:06 INFO - Return code: 0 01:37:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:37:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:37:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:37:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:37:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:37:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 01:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0239 --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 01:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TEVcK1Trdc/Render', 01:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:06 INFO - 'HOME': '/Users/cltbld', 01:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:06 INFO - 'LOGNAME': 'cltbld', 01:37:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:37:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:06 INFO - 'MOZ_NO_REMOTE': '1', 01:37:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:37:06 INFO - 'NO_EM_RESTART': '1', 01:37:06 INFO - 'PAGER': '/bin/cat', 01:37:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:06 INFO - 'PWD': '/builds/slave/test', 01:37:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:06 INFO - 'SHELL': '/bin/bash', 01:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAhrhFZU3m/Listeners', 01:37:06 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:06 INFO - 'USER': 'cltbld', 01:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:06 INFO - 'XPC_FLAGS': '0x0', 01:37:06 INFO - 'XPC_SERVICE_NAME': '0', 01:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 01:37:06 INFO - mozversion application_buildid: 20160906234616 01:37:06 INFO - mozversion application_changeset: d5338a598b481b9c4653c8b64ab70db66b9362b4 01:37:06 INFO - mozversion application_display_name: Nightly 01:37:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:37:06 INFO - mozversion application_name: Firefox 01:37:06 INFO - mozversion application_remotingname: firefox 01:37:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:37:06 INFO - mozversion application_vendor: Mozilla 01:37:06 INFO - mozversion application_version: 51.0a1 01:37:06 INFO - mozversion platform_buildid: 20160906234616 01:37:06 INFO - mozversion platform_changeset: d5338a598b481b9c4653c8b64ab70db66b9362b4 01:37:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:37:06 INFO - mozversion platform_version: 51.0a1 01:37:06 INFO - using testdate: 1473237426 01:37:06 INFO - actual date: 1473237426 01:37:06 INFO - starting webserver on 'localhost:49271' 01:37:06 INFO - SUITE-START | Running 2 tests 01:37:06 INFO - TEST-START | dromaeo_css 01:37:06 INFO - operating with platform_type : mac_ 01:37:06 INFO - Initialising browser for dromaeo_css test... 01:37:06 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpjdxxfj/profile http://localhost:49271/getInfo.html) 01:37:08 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 01:37:08 INFO - PROCESS | 1665 | colorDepth:24 01:37:08 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 01:37:08 INFO - PROCESS | 1665 | __metrics 01:37:09 INFO - PROCESS | 1665 | 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" 01:37:09 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:37:09 INFO - TEST-INFO | 1665: exit 0 01:37:09 INFO - Browser initialized. 01:37:09 INFO - Running cycle 1/1 for dromaeo_css test... 01:37:09 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpjdxxfj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:21 INFO - PROCESS | 1667 | RSS: Main: 232361984 01:37:21 INFO - PROCESS | 1667 | 01:38:12 INFO - PROCESS | 1667 | 1473237492793 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:40:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 01:40:26 INFO - PROCESS | 1667 | RSS: Main: 372199424 01:40:26 INFO - PROCESS | 1667 | 01:43:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 01:43:29 INFO - PROCESS | 1667 | RSS: Main: 373362688 01:43:29 INFO - PROCESS | 1667 | 01:46:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 01:46:32 INFO - PROCESS | 1667 | RSS: Main: 327823360 01:46:32 INFO - PROCESS | 1667 | 01:49:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 01:49:35 INFO - PROCESS | 1667 | RSS: Main: 355852288 01:49:35 INFO - PROCESS | 1667 | 01:52:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 01:52:39 INFO - PROCESS | 1667 | RSS: Main: 327462912 01:52:39 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 01:55:42 INFO - PROCESS | 1667 | RSS: Main: 532135936 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | __start_tp_report 01:55:42 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 01:55:42 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 01:55:42 INFO - PROCESS | 1667 | |i|pagename|runs| 01:55:42 INFO - PROCESS | 1667 | |0;dojo.html;3320.9003729888104;3332.067021234055;3368.6631336866317;3390.6439823818514;3419.914502137446;5678.34699009614;5826.854328641786;5831.766729330827;5855.912161317584;5878.647281163127;5807.245058142446;5843;5860.915730589844;5863;5870.471657550819;4509.278515437531;5129.589632829374;5257.921131183035;5314.149736042234;5314.963582101492;5348.224507446418;5356.517913387795;5358.517733403993;5363.168708850135;5370.979513892348;5263.736813159346;5279.023380674568;5304.336957880265;5320.095583750753;5357.116075847481;5354.678719276856;5376.220448035052;5386.865328366783;5389.9191512127345;5404.135338345845;5105.387353517602;5249.606279529051;5249.711265880375;5283.339582552181;5301.840944771663;5707.172459993278;5766.394528574523;5797.275340582427;5829.825105246807;5850.005499065169;5216.817411390621;6214.844628884269;6226.439620434137;6231.096491008779;6232.594881332699;6141.731496609858;6159.630422174639;6197.721102550396;6218.564700470969;6230.719617617173;5924.437178468039;5968.015277479168;6008.459238668541;6009.098635204667;6019.488343490809;6291.370862913672;6293.748250070037;6297.4962003039645;6300.936990630035;6305.842353941096;6121.3572574879;6147.524594097385;6166.506679465632;6204.224471941007;6242.188515492957;6179.81460556184;6198.659073750897;6234.438900498977;6237.251529816451;6256.937430625636;6217.409346112112;6237.937620623827;6246.000639897595;6248.375162483715;6251.531135164881;6140.320203811457;6145.924463218918;6200.937990620036;6245.937540624535;6258.624482531062;4980.975095124501;5027.069992051483;5045.9747701261995;5088.643794934319;5095.592352611856;4476.70901391415;4481.686281960231;4496.887577810516;4502.16709908668;4560.156371071363;5808.244928159313;5824.650520968756;5852.385499522489;5856.1508581255475;5859;5206.791728330824;6160.691965401712;6178.227721534809;6266;6266.937330626635;5824.359320474744;5832.300123985064;5843.649381037151;5847.473727364472;5879.764809407576;5414.431484694051;5424.918626220609;5446.400895901447;5457.263269458652;5460.808871689472;6180.443760061616;6240.781572644935;6302.905456418066;6309.432151106423;6378.489720822416;6213.689315534296;6227.564070515202;6267.655278959519;6285.717142728497;6287.96856015717;6370.203724534434;6383.39357761012;6388.1376014237485;6391.2330520337855;6391.265004524539;6111.04482360895;6182.969085154546;6207.596506227081;6232.314445410911;6235.688215589294;6271.529635277464;6273.96863015682;6274.403931626488;6279.215098112736;6292.496600271876;6164.106204600397;6221.440070393505;6231.937680623136;6233.532485063548;6238.625682459067;6069.180660610761;6125.907024958145;6138.570300078952;6158.0147176451555;6161.568690191643;6035.517158627387;6087.512998960161;6094.360092190256;6096.939030609815;6119.082137679384;6193.318734939243;6223.377662233922;6226.40849119333;6231;6243.500519958302;5867.823965281048;5937.643741375531;6013.218281623361;6057.666828324484;6117.265928088658 01:55:42 INFO - PROCESS | 1667 | |1;ext.html;522.292293941709;539.982133678017;549.2886711708335;557.8103444828757;558.2073455692915;29986.100416987498;31737.68262317376;31888.043358699248;40941.7952910235;42973.92565185872;43149.78425107878;43492;43569.34645980313;43686.12627747443;43718.56281437185;9715.46564938928;9885.851712224323;9903.257255705816;9929.702108936708;9930.553125109387;8497.150284971514;9744.269179811507;9848.162906152986;9858.704238872846;9865.556049977768;9843.31096823219;9862.950685246564;9864.112229899307;9880;9880.654177103803;9811.264155188353;9861.556229969668;9862.28787083547;9868;9870.45712485813;9769.51152442375;9854.90145098547;9861.556229969598;9864.556094975744;9866.210703143803;40414.39378409326;40677.79661101705;40713.59286407157;40796;40838.59161408377;3468.289000754839;3492.4586689063235;3542.8582856685703;3793.791341476218;3994.2410941920944;30131.548026779616;30510.389792204252;30546.389072218655;30993;31058.84470577655;3210.406074876132;3238.6437491875877;3239.627442844056;3272.198311413719;3370.0563842124243;516119;516294;518107;649257;649908;62830;63142;63239;63277.050844237376;63457;34208.82895585506;34523.482147767805;66863;66940.66529667418;67127.66436167787;15980.600484987735;16038.037717736739;16066.919665401598;16089.275982580812;16095.03429794216;12239.388030598435;12339;12339.93830030862;12346.197497162655;12400;18154.63690726178;18356.961468476915;18821.247150114115;19015.52461188481;19077.950712710935;6994.300569942965;7889.132195458495;7973.681052757941;8099.87850182236;8108;858.9699360522477;956.8421210500189;971.9805603887883;1007.6221416968637;1078.577073527144;26077.95688172452;29896.701032989826;29931.802727891078;30094;30549.08352749421;903.8779764731677;1006.8590397344377;1067.2102644043352;1123.1464087293716;1146.1174895330548;14989.325480353411;15269;15403.614909627126;15628.3748650055;15653.37386504527;685694;690108;690857;692357;696892;48393.49900765387;57397.426025740046;58105;58216.70891645514;58342.4165758337;1195.1633856300625;1240.4851986425713;1270.688681273094;1382.7787553991313;1386.937587808531;30174;30871;31015;31730;31749.20626984344;50587.74706126446;55904.44095559155;56775;57128.42871571231;61276.080858788046;635351;643541;643854;645124;645885;26250.343741406617;26814.731852681223;27047.594286085332;27986.860065700355;28111;47990;48332.79168020828;48672.783180420774;48907;49150;33374;39234.607653924235;39411.40926966019;40702.79648601738;41103;34480.48279275863;39183.2163356737;39223.607763921995;40126.79936600298;40620.59379406168;37331.880043598736;37601.62398376091;38242;38688;38775.6122438772;32087.67912320847;33178.17054573655;35551.82224088863;36231.63768362282;36500.63499365078;22081.558368832855;23024.309270721904;25622.359441014123;25934.48131037331;26643.866780665972 01:55:42 INFO - PROCESS | 1667 | |2;jquery.html;330.3393213572854;351.80826449584976;375.71257987639444;379.88793305974735;403.6226128569787;15355.692886142271;16707.498775036755;19825.33231352201;20344.69482957757;20448.38654840357;20471.692924606145;20783.16867325309;21037.579248415022;21139.682904756442;21237.362879113614;5744.454276843693;5784.82645520635;6032.849178770522;6066.969665151668;6086.147939288503;5534.723263836812;6018.187544681477;6023.367546407608;6040.335563088057;6040.637561746287;4123;6270.184875966118;6532.902006469907;6567.376099270562;6665.966670166642;6480.6435646039445;6504.869902601921;6556.639384833832;6632.104665870118;6644.700988455531;5249.370075590953;5615.550755939515;5617.859553511154;5626.803061892855;5630.324361076697;25242.24273271805;25294.988200472144;26051;26124.216273511825;26449.3387665308;2493.5137648158616;2548.4903019396197;2573.935651608706;2674.6790385153713;2841.176058942904;15133.621659458602;15357.923210383988;15699.37202511898;16087.597810054725;16152.757708634379;1808.5749848785524;1880.548668319607;1886.9433916982512;1922.3848368522079;1926.9421917342502;115475.84524154734;121001.78998209993;122773.38613306877;122829;126212;11185.32888026721;11396.658100257006;11415.315081095161;11429.714257143638;11634.53461861516;14255.215963122128;14284.57146285613;14304.85695143056;14306.213158276187;14328.785068223984;8250;8506.404551681322;8562.229399354088;8642.30861531076;8649.481031138152;8375.95812020936;8412.705555305534;8431.57842107892;8552.401331906707;8634.956825215833;4386.363977223285;4474.002418379693;4483.439570053743;4555.612772914273;4560.498345182027;1660.7342825147919;1867.5144462439864;1914.9425517234506;1924.191839427444;1925;593.9940600594025;700.3661686174021;750.7259850154654;834.220004295973;855.3542075732906;19689.507762306057;20679.483012924495;20796.58406831855;20855.58288834245;20877.89561052185;892.8839250897341;902.2195800632537;919.4299534288783;928.1553786054793;987.7431867714446;10977.066949309397;11363.545458181745;11404.714882127848;11417.828732569018;11428.542858285575;518581;534389.3280533572;596347.0365296379;617176;617662;76657;134359;142432;148367.51632483752;152997;971.9757006074905;997.7954519323521;1012.549415510091;1015.2690063154517;1022.2384323678758;15179.468718594797;15247.618809529851;15313.387464501295;19233.61532769309;19417.51456213606;53468.197977029595;54868.62828429321;55798.44201557933;57258;57430;81109.18890811017;81517.77723334273;83900;86144;87837.56081219553;7757.689692412466;7917.920820791718;7941.722039728582;8152.266296033148;8256.674669448383;10564.630237941636;12950.287734174333;13162.605121845976;13453.865461345262;13522.459101635824;11115;11291.3789741565;11570.88429115698;11597.014253788186;11812.468438920097;10003.899961000297;11345.262557933707;11453.370064646524;11625.709357266136;11798.587049453228;11420.771584568429;11484.827727583926;11694.47374868116;11834.99402550793;11863.40682965831;7043.499557657163;7256.1655409628565;7960.721374752087;7974;8298.917010830057;10531.736706582396;11413.201075924606;11575.652730418102;11643;11748.70628234301 01:55:42 INFO - PROCESS | 1667 | |3;mootools.html;1428.6928310413261;1474.8672619464246;1543.46750371122;1866.8879867207977;1897.800730923253;8477.533735644538;8997.46015239086;8997.820043599124;9003.5048072356;9027.819443611124;7844.843103137934;8022.799430014253;8414.537200453973;8691.348148888826;8750.08124146965;2161.735384799894;2282.7374851892077;2355.7879790818824;2403.0507949359994;2406.3984003999;2355;2388.7252965908883;2407.0612461140167;2411.2886698423918;2417.3231495181376;2387.295747754408;2394.1740099665676;2398.3164798032562;2423.4183795889035;2423.430493833948;2369.3958040699513;2392.85642861428;2396.5206958608346;2403.495265994126;2413.3725231439776;2381.4284571702665;2386.4033991502124;2387.4747555537756;2389.593769059264;2390.2351247600773;8630.525321107336;8777.210051095431;8781.824363512695;8800.8679869802;8809.691660791905;5288.391834938992;5444;5445.618806683534;5446.91829622556;5448.891022179534;3642.562892452923;3652.4156135018534;3700.759450635727;3721.8511259549587;3739.364308067635;1085.8371244313337;1094.7427354571669;1096.6381094238882;1111.2276967507585;1118.5805323003874;11337.206395552315;11413.144014198968;11516.654500365003;11523.193376463567;11569.36368499732;4577.931331030038;4630.652701047435;4836.032793441255;4846.854594362175;4868.9269660955115;10008.299419040597;10010.049045340827;10015;10023.849642255225;10029.297949143636;5377.919331210035;5378.1663842104535;5392.703401312965;5395.73021348931;5408.080626293539;4417.293233082692;4536.637069034469;4796.136695394863;4876.683015603974;4899.804007839718;3954.5056867891512;4016.758994460342;4074.9185016300103;4115.773632450244;4121.773302468333;1302.6222395505388;1326.6484381638872;1329.5745361484232;1333.5265980576949;1342.3221273256943;823.1521532821204;847.1993965702352;901.9774505637412;910.3854897943861;926.2358554192817;5512.91730624041;5752.511036561856;5975.372585878507;6064.151018857364;6206.131141640174;888.6045709659269;895.8880139982502;907;923.690563661181;947.4362754161263;3505.3339865425487;3798.6961043116485;3846.076941534068;3869.4389313549377;3917.6679928824337;10401.21990850674;10891.782164356668;10892.836607450894;10896.782064358827;10899.727506812234;10848.511816968256;10875.564977400974;10875.67372978796;10879.347239165676;10924.126069914388;6497.642629655415;8214.26071653554;8256.27542910838;8261.256486916087;8262.752117436487;5833.504152147115;5969.194158788508;6009.158717779602;6023.969880150571;6062.544989202648;9622.326437149331;9686.95156524241;9925.454100024568;9965.850512242467;9999.200063994718;10049.346792458467;10235.692929212135;10252.487375631039;10302.072813446826;10315.381077135096;6253.5622506424115;7268.636568171464;7382.9630851845395;7393.741219057307;7513.511621744788;8308.169183081885;8474.703385381483;8485.51503486875;8497.192766687154;8510.829783404173;7358.448116391185;7438.1818000018875;7477.140128885248;7536.1333446654335;7621.504602200839;7198.992141100145;7251.456140789356;7287.890681639886;7446.404287657083;7453.366463850417;6055.424734650202;6877.449804015567;6998.825029374103;7006.614636194854;7026.086608740831;4901.754912254301;4929.5809856161195;4975.154223781893;5001.599872010303;5068.113080210875;4788.11419887322;4881.51184881509;4963.305137280711;5042.344495215599;5168.560672342743 01:55:42 INFO - PROCESS | 1667 | |4;prototype.html;275.5233446138181;282.9957550636739;302.89852899278736;305.12581454133914;305.7294294549323;4825.78283977221;5207.609429292804;5261.8421447356595;5285.524302812746;5303.310569625944;4282.486101667804;4838.975805120978;5190.506901844319;5213.843584692456;5266.683998960056;3476.6349533299017;3802.2965751335955;3926;3950.9802450987704;3951.9012024699323;3431.5882094148737;3962.5734735495344;3964.762114273138;3967.444557761916;3977.8011099445057;3311.503274508819;3440.552728145338;3506.824658767064;3803.1442925341853;3947.54603220629;3590.2281009582935;3907.5701672815967;3919.2945269851416;3932.1742434088874;3967.3652215645648;2985.8954936577115;3246.9609724888046;3344.3645707315777;3363.4786608075788;3365.831708414594;5098.949010509921;5123.565401687533;5139.049275883974;5151.356080489939;5162.631902545829;4249.596288352602;4440.136112207608;4463.66522510813;4485.529019452975;4506.098780243964;4406.140802543506;4959.6528243023;5094.694318340874;5454.53636440903;5543.889122217533;3291.670832916713;3567.6789088981855;3695.9630403695887;3753.9812300938593;3754.755940863835;5435.934344948933;5699.658020518741;5742.875136199591;5781.682007489587;5791.536677065867;3320.7841490303535;3322.770574887259;3751.643593858579;3754.3805272129794;3757.3800322946954;3233.951490727641;3386.695197432243;3443.6900678939014;3638.5809534281652;3820.726667632754;3976.9602303977167;4249.043965107888;4458.5987261146;4472.932906006413;4488.663350248745;4913.484084171182;5203;5512.972435137879;5515.007298686195;5544.417836127229;1277.208130958793;1296.4814074370327;1300.219868079145;1304.302198323892;1304.3869381390732;729.712058217247;736.5784036808903;745.314310834034;790.6007466229518;799.9520028798174;3837;4085.55058943516;4220.704550681484;4382.729008587474;4394.780260986938;4734.644901632391;4739.739314337745;4751.64362672801;4764.76176191196;4805.342156955845;3903.8048097595006;4328.523862375137;4342.6742994275555;4360.4767427908855;4381.759003254788;2224.0103154096273;2358.386819426927;2487.4900645367657;2494.3888747257038;2516.282859385103;4515.732632662102;4656.930146047812;4747.385888797825;4921.26181072842;4931.630127740434;4674.555917187861;4849.781759820746;4879.4388645305535;4906.062105468392;5553.305836770403;5018.473060328686;5445.591580631469;5500.669959802424;5616.66300022;5951.404859514014;5467.753951072291;5655.038643430545;5667.291588551431;5684.550439637866;5710.685912274865;4724.913269947862;5628.240187574625;5655.7454914528125;5669.149627555899;5690.231818704422;4518.548145185455;5525.950069486785;5626.43735626434;5914.2311499504785;5985.970070149621;3540.8760693375607;3628.564572251347;3817.370133927871;3820.808959552067;3839.9232015360094;5455.809046683347;5527.585431092603;5743.396943320891;5759.337676167294;5763.697398393404;5484.890302194013;5489.533389661923;5491.478309560585;5500.862478438071;5536.4186760389575;5504.862378440571;5516.641418307958;5521;5603.55171586264;5634.971825140848;4569;5271.683698978073;5380.650257733235;5432.809851655173;5434.402215756185;4622.907541849211;5517.641353312022;5524.66851988882;5525.723713814374;5527.917081243865;4844.006978569455;5032.396112466381;5458.781648734006;5472;5520.488177878975 01:55:42 INFO - PROCESS | 1667 | |5;yui.html;718.9568625882446;721.9675863515171;738.3982054625479;756.5498528375618;816.3794220552028;15081.245937703112;16098.59753506163;16111.838881611166;16128.99999999997;16156.515304540868;12377.814332785016;14589.197594132345;14692;14714.11715297083;14761.92619036906;677.6645560447561;701.8701540215077;707.9646017699121;861.4099341950783;878.7627340618029;685.4379408884716;686.5057158845623;851.2296371783515;878.2315473960285;883.0507204754882;917.8485549884261;929.4051806843626;937.5312343828086;938.352536749644;943.3632298198722;907.577976241051;907.8592818113203;908.3641450984337;908.981820363589;909.6543313540845;541.7291354322839;675.787127054976;676.256118269904;677.0453660338899;677.6543962579071;14483.855161448357;14982.925085374613;15023.474178403703;15045;15050.698986020328;14245.857541424557;14491.492797752027;14673.633159170999;14884.181370024644;14967.401303947829;11825.408729563487;11827.516221593476;11877.703057423547;11924.224925379822;12183.756324873453;3821.808909554511;3836;3847.153626202231;3853.691704663648;3986.86045988389;115100.84899150985;116995;118088.22867656992;119598;119701;11081.591274557713;11183.44082795857;11200.83198752003;11346.48940797666;11659.88340116605;23896.283111506684;23898.761012389652;23904;23919.52160956771;23921.401964950666;7774.300312971746;8149.918500814916;8211.630476628567;8225.218604232588;8451.323894088458;4329.567043295709;4381.430414046217;4387.341898715218;4448.354988526646;4548.340490628906;3539;3695.7043436525014;3722.6091260417807;3729.2541491701227;3737.588865224823;676.9153855768029;682.8143480464818;684.0696652552524;686.807693845724;691.7025678958096;535.7053620508705;606.154414591651;630.8580569371854;638.256431257589;660.4089340040637;8112.107668156498;8204.958975205205;9488.383255088396;9545.713628591153;9653.72507869876;641.3169973977699;659.5317324699421;673.3367632881608;682.1119618176414;684.0935760117864;4297.033167537279;4614.76926153691;4621.79201935907;4661.067786442725;4666.230072038075;88023.67964480538;125870;162291.18854405652;162609;166566.16716916338;145480.27259863634;146202.26898865437;146298;147688.2615586915;148704.72019756667;84800;101348;104152.99999999849;121974;122928;8551.765261248924;8979.281657467256;9189.862152067859;9631.229501639711;9826.606935722491;58347.12479312898;60634.787304252786;60997.69501152466;61430.0785488227;64775.352246476934;80821;84419.15580844358;88427.55786221028;91651;93359.53320233355;12833.871661283267;12911.741765164456;13014.674633134247;13368.465261389825;13444.932775336061;14383;14482.782758258423;14526;14625.561233163022;14630.487932922297;13346.19922804635;13474.663133421744;13665.658358541115;13721.45114195421;14225.110299255257;13757;13765.38055787472;13914.234717090658;14120.858791411954;14400.351984160947;12826.935865320615;12903.806442903177;12933.482660693468;12999.675008124872;13222.140560863512;10684;11012.614558490415;11144.832827507431;11273.943630281796;11304.095672346357;9234.144395691901;10698.572057117628;10974.78899430175;11131.554737810397;11280.717982050515 01:55:42 INFO - PROCESS | 1667 | __end_tp_report 01:55:42 INFO - PROCESS | 1667 | __start_cc_report 01:55:42 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1353 01:55:42 INFO - PROCESS | 1667 | __end_cc_report 01:55:42 INFO - PROCESS | 1667 | __startTimestamp1473238542584__endTimestamp 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | ------- Summary: start ------- 01:55:42 INFO - PROCESS | 1667 | Number of tests: 6 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5796.69 Median:6069.18 stddev:632.90 (10.4%) stddev-sans-first:604.31 01:55:42 INFO - PROCESS | 1667 | Values: 3320.9 3332.1 3368.7 3390.6 3419.9 5678.3 5826.9 5831.8 5855.9 5878.6 5807.2 5843.0 5860.9 5863.0 5870.5 4509.3 5129.6 5257.9 5314.1 5315.0 5348.2 5356.5 5358.5 5363.2 5371.0 5263.7 5279.0 5304.3 5320.1 5357.1 5354.7 5376.2 5386.9 5389.9 5404.1 5105.4 5249.6 5249.7 5283.3 5301.8 5707.2 5766.4 5797.3 5829.8 5850.0 5216.8 6214.8 6226.4 6231.1 6232.6 6141.7 6159.6 6197.7 6218.6 6230.7 5924.4 5968.0 6008.5 6009.1 6019.5 6291.4 6293.7 6297.5 6300.9 6305.8 6121.4 6147.5 6166.5 6204.2 6242.2 6179.8 6198.7 6234.4 6237.3 6256.9 6217.4 6237.9 6246.0 6248.4 6251.5 6140.3 6145.9 6200.9 6245.9 6258.6 4981.0 5027.1 5046.0 5088.6 5095.6 4476.7 4481.7 4496.9 4502.2 4560.2 5808.2 5824.7 5852.4 5856.2 5859.0 5206.8 6160.7 6178.2 6266.0 6266.9 5824.4 5832.3 5843.6 5847.5 5879.8 5414.4 5424.9 5446.4 5457.3 5460.8 6180.4 6240.8 6302.9 6309.4 6378.5 6213.7 6227.6 6267.7 6285.7 6288.0 6370.2 6383.4 6388.1 6391.2 6391.3 6111.0 6183.0 6207.6 6232.3 6235.7 6271.5 6274.0 6274.4 6279.2 6292.5 6164.1 6221.4 6231.9 6233.5 6238.6 6069.2 6125.9 6138.6 6158.0 6161.6 6035.5 6087.5 6094.4 6096.9 6119.1 6193.3 6223.4 6226.4 6231.0 6243.5 5867.8 5937.6 6013.2 6057.7 6117.3 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:75697.40 Median:40690.30 stddev:170910.65 (420.0%) stddev-sans-first:171296.90 01:55:42 INFO - PROCESS | 1667 | Values: 522.3 540.0 549.3 557.8 558.2 29986.1 31737.7 31888.0 40941.8 42973.9 43149.8 43492.0 43569.3 43686.1 43718.6 9715.5 9885.9 9903.3 9929.7 9930.6 8497.2 9744.3 9848.2 9858.7 9865.6 9843.3 9863.0 9864.1 9880.0 9880.7 9811.3 9861.6 9862.3 9868.0 9870.5 9769.5 9854.9 9861.6 9864.6 9866.2 40414.4 40677.8 40713.6 40796.0 40838.6 3468.3 3492.5 3542.9 3793.8 3994.2 30131.5 30510.4 30546.4 30993.0 31058.8 3210.4 3238.6 3239.6 3272.2 3370.1 516119.0 516294.0 518107.0 649257.0 649908.0 62830.0 63142.0 63239.0 63277.1 63457.0 34208.8 34523.5 66863.0 66940.7 67127.7 15980.6 16038.0 16066.9 16089.3 16095.0 12239.4 12339.0 12339.9 12346.2 12400.0 18154.6 18357.0 18821.2 19015.5 19078.0 6994.3 7889.1 7973.7 8099.9 8108.0 859.0 956.8 972.0 1007.6 1078.6 26078.0 29896.7 29931.8 30094.0 30549.1 903.9 1006.9 1067.2 1123.1 1146.1 14989.3 15269.0 15403.6 15628.4 15653.4 685694.0 690108.0 690857.0 692357.0 696892.0 48393.5 57397.4 58105.0 58216.7 58342.4 1195.2 1240.5 1270.7 1382.8 1386.9 30174.0 30871.0 31015.0 31730.0 31749.2 50587.7 55904.4 56775.0 57128.4 61276.1 635351.0 643541.0 643854.0 645124.0 645885.0 26250.3 26814.7 27047.6 27986.9 28111.0 47990.0 48332.8 48672.8 48907.0 49150.0 33374.0 39234.6 39411.4 40702.8 41103.0 34480.5 39183.2 39223.6 40126.8 40620.6 37331.9 37601.6 38242.0 38688.0 38775.6 32087.7 33178.2 35551.8 36231.6 36500.6 22081.6 23024.3 25622.4 25934.5 26643.9 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34998.01 Median:2561.21 stddev:97079.60 (3790.4%) stddev-sans-first:97317.04 01:55:42 INFO - PROCESS | 1667 | Values: 330.3 351.8 375.7 379.9 403.6 15355.7 16707.5 19825.3 20344.7 20448.4 20471.7 20783.2 21037.6 21139.7 21237.4 5744.5 5784.8 6032.8 6067.0 6086.1 5534.7 6018.2 6023.4 6040.3 6040.6 4123.0 6270.2 6532.9 6567.4 6666.0 6480.6 6504.9 6556.6 6632.1 6644.7 5249.4 5615.6 5617.9 5626.8 5630.3 25242.2 25295.0 26051.0 26124.2 26449.3 2493.5 2548.5 2573.9 2674.7 2841.2 15133.6 15357.9 15699.4 16087.6 16152.8 1808.6 1880.5 1886.9 1922.4 1926.9 115475.8 121001.8 122773.4 122829.0 126212.0 11185.3 11396.7 11415.3 11429.7 11634.5 14255.2 14284.6 14304.9 14306.2 14328.8 8250.0 8506.4 8562.2 8642.3 8649.5 8376.0 8412.7 8431.6 8552.4 8635.0 4386.4 4474.0 4483.4 4555.6 4560.5 1660.7 1867.5 1914.9 1924.2 1925.0 594.0 700.4 750.7 834.2 855.4 19689.5 20679.5 20796.6 20855.6 20877.9 892.9 902.2 919.4 928.2 987.7 10977.1 11363.5 11404.7 11417.8 11428.5 518581.0 534389.3 596347.0 617176.0 617662.0 76657.0 134359.0 142432.0 148367.5 152997.0 972.0 997.8 1012.5 1015.3 1022.2 15179.5 15247.6 15313.4 19233.6 19417.5 53468.2 54868.6 55798.4 57258.0 57430.0 81109.2 81517.8 83900.0 86144.0 87837.6 7757.7 7917.9 7941.7 8152.3 8256.7 10564.6 12950.3 13162.6 13453.9 13522.5 11115.0 11291.4 11570.9 11597.0 11812.5 10003.9 11345.3 11453.4 11625.7 11798.6 11420.8 11484.8 11694.5 11835.0 11863.4 7043.5 7256.2 7960.7 7974.0 8298.9 10531.7 11413.2 11575.7 11643.0 11748.7 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5715.56 Median:4890.66 stddev:3186.75 (65.2%) stddev-sans-first:3179.41 01:55:42 INFO - PROCESS | 1667 | Values: 1428.7 1474.9 1543.5 1866.9 1897.8 8477.5 8997.5 8997.8 9003.5 9027.8 7844.8 8022.8 8414.5 8691.3 8750.1 2161.7 2282.7 2355.8 2403.1 2406.4 2355.0 2388.7 2407.1 2411.3 2417.3 2387.3 2394.2 2398.3 2423.4 2423.4 2369.4 2392.9 2396.5 2403.5 2413.4 2381.4 2386.4 2387.5 2389.6 2390.2 8630.5 8777.2 8781.8 8800.9 8809.7 5288.4 5444.0 5445.6 5446.9 5448.9 3642.6 3652.4 3700.8 3721.9 3739.4 1085.8 1094.7 1096.6 1111.2 1118.6 11337.2 11413.1 11516.7 11523.2 11569.4 4577.9 4630.7 4836.0 4846.9 4868.9 10008.3 10010.0 10015.0 10023.8 10029.3 5377.9 5378.2 5392.7 5395.7 5408.1 4417.3 4536.6 4796.1 4876.7 4899.8 3954.5 4016.8 4074.9 4115.8 4121.8 1302.6 1326.6 1329.6 1333.5 1342.3 823.2 847.2 902.0 910.4 926.2 5512.9 5752.5 5975.4 6064.2 6206.1 888.6 895.9 907.0 923.7 947.4 3505.3 3798.7 3846.1 3869.4 3917.7 10401.2 10891.8 10892.8 10896.8 10899.7 10848.5 10875.6 10875.7 10879.3 10924.1 6497.6 8214.3 8256.3 8261.3 8262.8 5833.5 5969.2 6009.2 6024.0 6062.5 9622.3 9687.0 9925.5 9965.9 9999.2 10049.3 10235.7 10252.5 10302.1 10315.4 6253.6 7268.6 7383.0 7393.7 7513.5 8308.2 8474.7 8485.5 8497.2 8510.8 7358.4 7438.2 7477.1 7536.1 7621.5 7199.0 7251.5 7287.9 7446.4 7453.4 6055.4 6877.4 6998.8 7006.6 7026.1 4901.8 4929.6 4975.2 5001.6 5068.1 4788.1 4881.5 4963.3 5042.3 5168.6 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4315.36 Median:4743.56 stddev:1371.31 (28.9%) stddev-sans-first:1341.21 01:55:42 INFO - PROCESS | 1667 | Values: 275.5 283.0 302.9 305.1 305.7 4825.8 5207.6 5261.8 5285.5 5303.3 4282.5 4839.0 5190.5 5213.8 5266.7 3476.6 3802.3 3926.0 3951.0 3951.9 3431.6 3962.6 3964.8 3967.4 3977.8 3311.5 3440.6 3506.8 3803.1 3947.5 3590.2 3907.6 3919.3 3932.2 3967.4 2985.9 3247.0 3344.4 3363.5 3365.8 5098.9 5123.6 5139.0 5151.4 5162.6 4249.6 4440.1 4463.7 4485.5 4506.1 4406.1 4959.7 5094.7 5454.5 5543.9 3291.7 3567.7 3696.0 3754.0 3754.8 5435.9 5699.7 5742.9 5781.7 5791.5 3320.8 3322.8 3751.6 3754.4 3757.4 3234.0 3386.7 3443.7 3638.6 3820.7 3977.0 4249.0 4458.6 4472.9 4488.7 4913.5 5203.0 5513.0 5515.0 5544.4 1277.2 1296.5 1300.2 1304.3 1304.4 729.7 736.6 745.3 790.6 800.0 3837.0 4085.6 4220.7 4382.7 4394.8 4734.6 4739.7 4751.6 4764.8 4805.3 3903.8 4328.5 4342.7 4360.5 4381.8 2224.0 2358.4 2487.5 2494.4 2516.3 4515.7 4656.9 4747.4 4921.3 4931.6 4674.6 4849.8 4879.4 4906.1 5553.3 5018.5 5445.6 5500.7 5616.7 5951.4 5467.8 5655.0 5667.3 5684.6 5710.7 4724.9 5628.2 5655.7 5669.1 5690.2 4518.5 5526.0 5626.4 5914.2 5986.0 3540.9 3628.6 3817.4 3820.8 3839.9 5455.8 5527.6 5743.4 5759.3 5763.7 5484.9 5489.5 5491.5 5500.9 5536.4 5504.9 5516.6 5521.0 5603.6 5635.0 4569.0 5271.7 5380.7 5432.8 5434.4 4622.9 5517.6 5524.7 5525.7 5527.9 4844.0 5032.4 5458.8 5472.0 5520.5 01:55:42 INFO - PROCESS | 1667 | 01:55:42 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:25235.05 Median:3733.42 stddev:40843.94 (1094.0%) stddev-sans-first:40917.04 01:55:42 INFO - PROCESS | 1667 | Values: 719.0 722.0 738.4 756.5 816.4 15081.2 16098.6 16111.8 16129.0 16156.5 12377.8 14589.2 14692.0 14714.1 14761.9 677.7 701.9 708.0 861.4 878.8 685.4 686.5 851.2 878.2 883.1 917.8 929.4 937.5 938.4 943.4 907.6 907.9 908.4 909.0 909.7 541.7 675.8 676.3 677.0 677.7 14483.9 14982.9 15023.5 15045.0 15050.7 14245.9 14491.5 14673.6 14884.2 14967.4 11825.4 11827.5 11877.7 11924.2 12183.8 3821.8 3836.0 3847.2 3853.7 3986.9 115100.8 116995.0 118088.2 119598.0 119701.0 11081.6 11183.4 11200.8 11346.5 11659.9 23896.3 23898.8 23904.0 23919.5 23921.4 7774.3 8149.9 8211.6 8225.2 8451.3 4329.6 4381.4 4387.3 4448.4 4548.3 3539.0 3695.7 3722.6 3729.3 3737.6 676.9 682.8 684.1 686.8 691.7 535.7 606.2 630.9 638.3 660.4 8112.1 8205.0 9488.4 9545.7 9653.7 641.3 659.5 673.3 682.1 684.1 4297.0 4614.8 4621.8 4661.1 4666.2 88023.7 125870.0 162291.2 162609.0 166566.2 145480.3 146202.3 146298.0 147688.3 148704.7 84800.0 101348.0 104153.0 121974.0 122928.0 8551.8 8979.3 9189.9 9631.2 9826.6 58347.1 60634.8 60997.7 61430.1 64775.4 80821.0 84419.2 88427.6 91651.0 93359.5 12833.9 12911.7 13014.7 13368.5 13444.9 14383.0 14482.8 14526.0 14625.6 14630.5 13346.2 13474.7 13665.7 13721.5 14225.1 13757.0 13765.4 13914.2 14120.9 14400.4 12826.9 12903.8 12933.5 12999.7 13222.1 10684.0 11012.6 11144.8 11273.9 11304.1 9234.1 10698.6 10974.8 11131.6 11280.7 01:55:42 INFO - PROCESS | 1667 | -------- Summary: end -------- 01:55:42 INFO - PROCESS | 1667 | 01:55:43 INFO - TEST-INFO | 1667: exit 0 01:55:43 INFO - TEST-OK | dromaeo_css | took 1116731ms 01:55:43 INFO - TEST-START | kraken 01:55:43 INFO - operating with platform_type : mac_ 01:55:43 INFO - Initialising browser for kraken test... 01:55:43 INFO - TEST-INFO | started process 1787 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpOZlWwe/profile http://localhost:49271/getInfo.html) 01:55:45 INFO - PROCESS | 1787 | __metrics Screen width/height:1600/1200 01:55:45 INFO - PROCESS | 1787 | colorDepth:24 01:55:45 INFO - PROCESS | 1787 | Browser inner width/height: 1024/689 01:55:45 INFO - PROCESS | 1787 | __metrics 01:55:45 INFO - PROCESS | 1787 | 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" 01:55:45 INFO - PROCESS | 1787 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:55:46 INFO - TEST-INFO | 1787: exit 0 01:55:46 INFO - Browser initialized. 01:55:46 INFO - Running cycle 1/1 for kraken test... 01:55:46 INFO - TEST-INFO | started process 1788 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpOZlWwe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:55:58 INFO - PROCESS | 1788 | RSS: Main: 232402944 01:55:58 INFO - PROCESS | 1788 | 01:56:01 INFO - PROCESS | 1788 | 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) 01:56:01 INFO - PROCESS | 1788 | RSS: Main: 291184640 01:56:01 INFO - PROCESS | 1788 | 01:56:04 INFO - PROCESS | 1788 | 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) 01:56:05 INFO - PROCESS | 1788 | RSS: Main: 288350208 01:56:05 INFO - PROCESS | 1788 | 01:56:08 INFO - PROCESS | 1788 | 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) 01:56:09 INFO - PROCESS | 1788 | RSS: Main: 378134528 01:56:09 INFO - PROCESS | 1788 | 01:56:11 INFO - PROCESS | 1788 | 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) 01:56:12 INFO - PROCESS | 1788 | RSS: Main: 280829952 01:56:12 INFO - PROCESS | 1788 | 01:56:14 INFO - PROCESS | 1788 | 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) 01:56:14 INFO - PROCESS | 1788 | RSS: Main: 285343744 01:56:14 INFO - PROCESS | 1788 | 01:56:17 INFO - PROCESS | 1788 | 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) 01:56:18 INFO - PROCESS | 1788 | RSS: Main: 324173824 01:56:18 INFO - PROCESS | 1788 | 01:56:20 INFO - PROCESS | 1788 | 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) 01:56:21 INFO - PROCESS | 1788 | RSS: Main: 325763072 01:56:21 INFO - PROCESS | 1788 | 01:56:24 INFO - PROCESS | 1788 | 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) 01:56:24 INFO - PROCESS | 1788 | RSS: Main: 323080192 01:56:24 INFO - PROCESS | 1788 | 01:56:26 INFO - PROCESS | 1788 | 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) 01:56:27 INFO - PROCESS | 1788 | RSS: Main: 297775104 01:56:27 INFO - PROCESS | 1788 | 01:56:28 INFO - PROCESS | 1788 | 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) 01:56:29 INFO - PROCESS | 1788 | RSS: Main: 304181248 01:56:29 INFO - PROCESS | 1788 | 01:56:31 INFO - PROCESS | 1788 | 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) 01:56:32 INFO - PROCESS | 1788 | RSS: Main: 303124480 01:56:32 INFO - PROCESS | 1788 | 01:56:35 INFO - PROCESS | 1788 | 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) 01:56:36 INFO - PROCESS | 1788 | RSS: Main: 314322944 01:56:36 INFO - PROCESS | 1788 | 01:56:38 INFO - PROCESS | 1788 | 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) 01:56:39 INFO - PROCESS | 1788 | RSS: Main: 316829696 01:56:39 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | 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) 01:56:41 INFO - PROCESS | 1788 | RSS: Main: 360517632 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | __start_tp_report 01:56:41 INFO - PROCESS | 1788 | _x_x_mozilla_page_load 01:56:41 INFO - PROCESS | 1788 | _x_x_mozilla_page_load_details 01:56:41 INFO - PROCESS | 1788 | |i|pagename|runs| 01:56:41 INFO - PROCESS | 1788 | |0;ai-astar;114;111;117;114;114;113;114;113;113;123 01:56:41 INFO - PROCESS | 1788 | |1;audio-beat-detection;146;113;129;162;109;128;127;107;158;106 01:56:41 INFO - PROCESS | 1788 | |2;audio-dft;185;185;185;184;184;184;219;256;183;185 01:56:41 INFO - PROCESS | 1788 | |3;audio-fft;78;72;75;155;70;57;57;59;139;56 01:56:41 INFO - PROCESS | 1788 | |4;audio-oscillator;83;83;135;69;70;91;114;68;71;115 01:56:41 INFO - PROCESS | 1788 | |5;imaging-gaussian-blur;86;83;87;84;87;91;134;83;91;84 01:56:41 INFO - PROCESS | 1788 | |6;imaging-darkroom;96;96;96;96;95;96;96;95;95;95 01:56:41 INFO - PROCESS | 1788 | |7;imaging-desaturate;87;86;84;85;86;86;86;84;85;87 01:56:41 INFO - PROCESS | 1788 | |8;json-parse-financial;56;54;55;50;52;54;55;53;53;53 01:56:41 INFO - PROCESS | 1788 | |9;json-stringify-tinderbox;64;65;67;73;72;75;69;69;70;71 01:56:41 INFO - PROCESS | 1788 | |10;stanford-crypto-aes;86;86;85;90;83;86;85;85;86;91 01:56:41 INFO - PROCESS | 1788 | |11;stanford-crypto-ccm;138;141;142;148;139;139;141;138;245;138 01:56:41 INFO - PROCESS | 1788 | |12;stanford-crypto-pbkdf2;168;168;169;166;167;170;168;166;168;169 01:56:41 INFO - PROCESS | 1788 | |13;stanford-crypto-sha256-iterative;66;67;69;67;65;67;68;67;67;65 01:56:41 INFO - PROCESS | 1788 | __end_tp_report 01:56:41 INFO - PROCESS | 1788 | __start_cc_report 01:56:41 INFO - PROCESS | 1788 | _x_x_mozilla_cycle_collect,1944 01:56:41 INFO - PROCESS | 1788 | __end_cc_report 01:56:41 INFO - PROCESS | 1788 | __startTimestamp1473238601440__endTimestamp 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | ------- Summary: start ------- 01:56:41 INFO - PROCESS | 1788 | Number of tests: 14 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.00 stddev:3.31 (2.9%) stddev-sans-first:3.50 01:56:41 INFO - PROCESS | 1788 | Values: 114.0 111.0 117.0 114.0 114.0 113.0 114.0 113.0 113.0 123.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:128.50 stddev:20.79 (16.2%) stddev-sans-first:21.07 01:56:41 INFO - PROCESS | 1788 | Values: 146.0 113.0 129.0 162.0 109.0 128.0 127.0 107.0 158.0 106.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#2] audio-dft Cycles:10 Average:195.00 Median:185.00 stddev:24.05 (13.0%) stddev-sans-first:25.23 01:56:41 INFO - PROCESS | 1788 | Values: 185.0 185.0 185.0 184.0 184.0 184.0 219.0 256.0 183.0 185.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#3] audio-fft Cycles:10 Average:81.80 Median:64.50 stddev:35.50 (55.0%) stddev-sans-first:37.63 01:56:41 INFO - PROCESS | 1788 | Values: 78.0 72.0 75.0 155.0 70.0 57.0 57.0 59.0 139.0 56.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:70.50 stddev:23.60 (33.5%) stddev-sans-first:24.89 01:56:41 INFO - PROCESS | 1788 | Values: 83.0 83.0 135.0 69.0 70.0 91.0 114.0 68.0 71.0 115.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:86.50 stddev:15.39 (17.8%) stddev-sans-first:16.22 01:56:41 INFO - PROCESS | 1788 | Values: 86.0 83.0 87.0 84.0 87.0 91.0 134.0 83.0 91.0 84.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 01:56:41 INFO - PROCESS | 1788 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.07 (1.2%) stddev-sans-first:1.01 01:56:41 INFO - PROCESS | 1788 | Values: 87.0 86.0 84.0 85.0 86.0 86.0 86.0 84.0 85.0 87.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.72 (3.2%) stddev-sans-first:1.56 01:56:41 INFO - PROCESS | 1788 | Values: 56.0 54.0 55.0 50.0 52.0 54.0 55.0 53.0 53.0 53.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.50 stddev:3.47 (4.9%) stddev-sans-first:3.06 01:56:41 INFO - PROCESS | 1788 | Values: 64.0 65.0 67.0 73.0 72.0 75.0 69.0 69.0 70.0 71.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.00 stddev:2.41 (2.8%) stddev-sans-first:2.55 01:56:41 INFO - PROCESS | 1788 | Values: 86.0 86.0 85.0 90.0 83.0 86.0 85.0 85.0 86.0 91.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:141.00 stddev:33.20 (23.5%) stddev-sans-first:34.89 01:56:41 INFO - PROCESS | 1788 | Values: 138.0 141.0 142.0 148.0 139.0 139.0 141.0 138.0 245.0 138.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 01:56:41 INFO - PROCESS | 1788 | Values: 168.0 168.0 169.0 166.0 167.0 170.0 168.0 166.0 168.0 169.0 01:56:41 INFO - PROCESS | 1788 | 01:56:41 INFO - PROCESS | 1788 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 01:56:41 INFO - PROCESS | 1788 | Values: 66.0 67.0 69.0 67.0 65.0 67.0 68.0 67.0 67.0 65.0 01:56:41 INFO - PROCESS | 1788 | -------- Summary: end -------- 01:56:41 INFO - PROCESS | 1788 | 01:56:42 INFO - TEST-INFO | 1788: exit 0 01:56:42 INFO - TEST-OK | kraken | took 59050ms 01:56:42 INFO - SUITE-END | took 1175s 01:56:42 INFO - Completed test suite (00:19:36) 01:56:42 INFO - javascript benchmark 01:56:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.9003729888104, 3332.067021234055, 3368.6631336866317, 3390.6439823818514, 3419.914502137446, 5678.34699009614, 5826.854328641786, 5831.766729330827, 5855.912161317584, 5878.647281163127, 5807.245058142446, 5843.0, 5860.915730589844, 5863.0, 5870.471657550819, 4509.278515437531, 5129.589632829374, 5257.921131183035, 5314.149736042234, 5314.963582101492, 5348.224507446418, 5356.517913387795, 5358.517733403993, 5363.168708850135, 5370.979513892348, 5263.736813159346, 5279.023380674568, 5304.336957880265, 5320.095583750753, 5357.116075847481, 5354.678719276856, 5376.220448035052, 5386.865328366783, 5389.9191512127345, 5404.135338345845, 5105.387353517602, 5249.606279529051, 5249.711265880375, 5283.339582552181, 5301.840944771663, 5707.172459993278, 5766.394528574523, 5797.275340582427, 5829.825105246807, 5850.005499065169, 5216.817411390621, 6214.844628884269, 6226.439620434137, 6231.096491008779, 6232.594881332699, 6141.731496609858, 6159.630422174639, 6197.721102550396, 6218.564700470969, 6230.719617617173, 5924.437178468039, 5968.015277479168, 6008.459238668541, 6009.098635204667, 6019.488343490809, 6291.370862913672, 6293.748250070037, 6297.4962003039645, 6300.936990630035, 6305.842353941096, 6121.3572574879, 6147.524594097385, 6166.506679465632, 6204.224471941007, 6242.188515492957, 6179.81460556184, 6198.659073750897, 6234.438900498977, 6237.251529816451, 6256.937430625636, 6217.409346112112, 6237.937620623827, 6246.000639897595, 6248.375162483715, 6251.531135164881, 6140.320203811457, 6145.924463218918, 6200.937990620036, 6245.937540624535, 6258.624482531062, 4980.975095124501, 5027.069992051483, 5045.9747701261995, 5088.643794934319, 5095.592352611856, 4476.70901391415, 4481.686281960231, 4496.887577810516, 4502.16709908668, 4560.156371071363, 5808.244928159313, 5824.650520968756, 5852.385499522489, 5856.1508581255475, 5859.0, 5206.791728330824, 6160.691965401712, 6178.227721534809, 6266.0, 6266.937330626635, 5824.359320474744, 5832.300123985064, 5843.649381037151, 5847.473727364472, 5879.764809407576, 5414.431484694051, 5424.918626220609, 5446.400895901447, 5457.263269458652, 5460.808871689472, 6180.443760061616, 6240.781572644935, 6302.905456418066, 6309.432151106423, 6378.489720822416, 6213.689315534296, 6227.564070515202, 6267.655278959519, 6285.717142728497, 6287.96856015717, 6370.203724534434, 6383.39357761012, 6388.1376014237485, 6391.2330520337855, 6391.265004524539, 6111.04482360895, 6182.969085154546, 6207.596506227081, 6232.314445410911, 6235.688215589294, 6271.529635277464, 6273.96863015682, 6274.403931626488, 6279.215098112736, 6292.496600271876, 6164.106204600397, 6221.440070393505, 6231.937680623136, 6233.532485063548, 6238.625682459067, 6069.180660610761, 6125.907024958145, 6138.570300078952, 6158.0147176451555, 6161.568690191643, 6035.517158627387, 6087.512998960161, 6094.360092190256, 6096.939030609815, 6119.082137679384, 6193.318734939243, 6223.377662233922, 6226.40849119333, 6231.0, 6243.500519958302, 5867.823965281048, 5937.643741375531, 6013.218281623361, 6057.666828324484, 6117.265928088658], "value": 5756.519853054511, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.292293941709, 539.982133678017, 549.2886711708335, 557.8103444828757, 558.2073455692915, 29986.100416987498, 31737.68262317376, 31888.043358699248, 40941.7952910235, 42973.92565185872, 43149.78425107878, 43492.0, 43569.34645980313, 43686.12627747443, 43718.56281437185, 9715.46564938928, 9885.851712224323, 9903.257255705816, 9929.702108936708, 9930.553125109387, 8497.150284971514, 9744.269179811507, 9848.162906152986, 9858.704238872846, 9865.556049977768, 9843.31096823219, 9862.950685246564, 9864.112229899307, 9880.0, 9880.654177103803, 9811.264155188353, 9861.556229969668, 9862.28787083547, 9868.0, 9870.45712485813, 9769.51152442375, 9854.90145098547, 9861.556229969598, 9864.556094975744, 9866.210703143803, 40414.39378409326, 40677.79661101705, 40713.59286407157, 40796.0, 40838.59161408377, 3468.289000754839, 3492.4586689063235, 3542.8582856685703, 3793.791341476218, 3994.2410941920944, 30131.548026779616, 30510.389792204252, 30546.389072218655, 30993.0, 31058.84470577655, 3210.406074876132, 3238.6437491875877, 3239.627442844056, 3272.198311413719, 3370.0563842124243, 516119.0, 516294.0, 518107.0, 649257.0, 649908.0, 62830.0, 63142.0, 63239.0, 63277.050844237376, 63457.0, 34208.82895585506, 34523.482147767805, 66863.0, 66940.66529667418, 67127.66436167787, 15980.600484987735, 16038.037717736739, 16066.919665401598, 16089.275982580812, 16095.03429794216, 12239.388030598435, 12339.0, 12339.93830030862, 12346.197497162655, 12400.0, 18154.63690726178, 18356.961468476915, 18821.247150114115, 19015.52461188481, 19077.950712710935, 6994.300569942965, 7889.132195458495, 7973.681052757941, 8099.87850182236, 8108.0, 858.9699360522477, 956.8421210500189, 971.9805603887883, 1007.6221416968637, 1078.577073527144, 26077.95688172452, 29896.701032989826, 29931.802727891078, 30094.0, 30549.08352749421, 903.8779764731677, 1006.8590397344377, 1067.2102644043352, 1123.1464087293716, 1146.1174895330548, 14989.325480353411, 15269.0, 15403.614909627126, 15628.3748650055, 15653.37386504527, 685694.0, 690108.0, 690857.0, 692357.0, 696892.0, 48393.49900765387, 57397.426025740046, 58105.0, 58216.70891645514, 58342.4165758337, 1195.1633856300625, 1240.4851986425713, 1270.688681273094, 1382.7787553991313, 1386.937587808531, 30174.0, 30871.0, 31015.0, 31730.0, 31749.20626984344, 50587.74706126446, 55904.44095559155, 56775.0, 57128.42871571231, 61276.080858788046, 635351.0, 643541.0, 643854.0, 645124.0, 645885.0, 26250.343741406617, 26814.731852681223, 27047.594286085332, 27986.860065700355, 28111.0, 47990.0, 48332.79168020828, 48672.783180420774, 48907.0, 49150.0, 33374.0, 39234.607653924235, 39411.40926966019, 40702.79648601738, 41103.0, 34480.48279275863, 39183.2163356737, 39223.607763921995, 40126.79936600298, 40620.59379406168, 37331.880043598736, 37601.62398376091, 38242.0, 38688.0, 38775.6122438772, 32087.67912320847, 33178.17054573655, 35551.82224088863, 36231.63768362282, 36500.63499365078, 22081.558368832855, 23024.309270721904, 25622.359441014123, 25934.48131037331, 26643.866780665972], "value": 20254.881272556224, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.3393213572854, 351.80826449584976, 375.71257987639444, 379.88793305974735, 403.6226128569787, 15355.692886142271, 16707.498775036755, 19825.33231352201, 20344.69482957757, 20448.38654840357, 20471.692924606145, 20783.16867325309, 21037.579248415022, 21139.682904756442, 21237.362879113614, 5744.454276843693, 5784.82645520635, 6032.849178770522, 6066.969665151668, 6086.147939288503, 5534.723263836812, 6018.187544681477, 6023.367546407608, 6040.335563088057, 6040.637561746287, 4123.0, 6270.184875966118, 6532.902006469907, 6567.376099270562, 6665.966670166642, 6480.6435646039445, 6504.869902601921, 6556.639384833832, 6632.104665870118, 6644.700988455531, 5249.370075590953, 5615.550755939515, 5617.859553511154, 5626.803061892855, 5630.324361076697, 25242.24273271805, 25294.988200472144, 26051.0, 26124.216273511825, 26449.3387665308, 2493.5137648158616, 2548.4903019396197, 2573.935651608706, 2674.6790385153713, 2841.176058942904, 15133.621659458602, 15357.923210383988, 15699.37202511898, 16087.597810054725, 16152.757708634379, 1808.5749848785524, 1880.548668319607, 1886.9433916982512, 1922.3848368522079, 1926.9421917342502, 115475.84524154734, 121001.78998209993, 122773.38613306877, 122829.0, 126212.0, 11185.32888026721, 11396.658100257006, 11415.315081095161, 11429.714257143638, 11634.53461861516, 14255.215963122128, 14284.57146285613, 14304.85695143056, 14306.213158276187, 14328.785068223984, 8250.0, 8506.404551681322, 8562.229399354088, 8642.30861531076, 8649.481031138152, 8375.95812020936, 8412.705555305534, 8431.57842107892, 8552.401331906707, 8634.956825215833, 4386.363977223285, 4474.002418379693, 4483.439570053743, 4555.612772914273, 4560.498345182027, 1660.7342825147919, 1867.5144462439864, 1914.9425517234506, 1924.191839427444, 1925.0, 593.9940600594025, 700.3661686174021, 750.7259850154654, 834.220004295973, 855.3542075732906, 19689.507762306057, 20679.483012924495, 20796.58406831855, 20855.58288834245, 20877.89561052185, 892.8839250897341, 902.2195800632537, 919.4299534288783, 928.1553786054793, 987.7431867714446, 10977.066949309397, 11363.545458181745, 11404.714882127848, 11417.828732569018, 11428.542858285575, 518581.0, 534389.3280533572, 596347.0365296379, 617176.0, 617662.0, 76657.0, 134359.0, 142432.0, 148367.51632483752, 152997.0, 971.9757006074905, 997.7954519323521, 1012.549415510091, 1015.2690063154517, 1022.2384323678758, 15179.468718594797, 15247.618809529851, 15313.387464501295, 19233.61532769309, 19417.51456213606, 53468.197977029595, 54868.62828429321, 55798.44201557933, 57258.0, 57430.0, 81109.18890811017, 81517.77723334273, 83900.0, 86144.0, 87837.56081219553, 7757.689692412466, 7917.920820791718, 7941.722039728582, 8152.266296033148, 8256.674669448383, 10564.630237941636, 12950.287734174333, 13162.605121845976, 13453.865461345262, 13522.459101635824, 11115.0, 11291.3789741565, 11570.88429115698, 11597.014253788186, 11812.468438920097, 10003.899961000297, 11345.262557933707, 11453.370064646524, 11625.709357266136, 11798.587049453228, 11420.771584568429, 11484.827727583926, 11694.47374868116, 11834.99402550793, 11863.40682965831, 7043.499557657163, 7256.1655409628565, 7960.721374752087, 7974.0, 8298.917010830057, 10531.736706582396, 11413.201075924606, 11575.652730418102, 11643.0, 11748.70628234301], "value": 9711.226304701553, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.6928310413261, 1474.8672619464246, 1543.46750371122, 1866.8879867207977, 1897.800730923253, 8477.533735644538, 8997.46015239086, 8997.820043599124, 9003.5048072356, 9027.819443611124, 7844.843103137934, 8022.799430014253, 8414.537200453973, 8691.348148888826, 8750.08124146965, 2161.735384799894, 2282.7374851892077, 2355.7879790818824, 2403.0507949359994, 2406.3984003999, 2355.0, 2388.7252965908883, 2407.0612461140167, 2411.2886698423918, 2417.3231495181376, 2387.295747754408, 2394.1740099665676, 2398.3164798032562, 2423.4183795889035, 2423.430493833948, 2369.3958040699513, 2392.85642861428, 2396.5206958608346, 2403.495265994126, 2413.3725231439776, 2381.4284571702665, 2386.4033991502124, 2387.4747555537756, 2389.593769059264, 2390.2351247600773, 8630.525321107336, 8777.210051095431, 8781.824363512695, 8800.8679869802, 8809.691660791905, 5288.391834938992, 5444.0, 5445.618806683534, 5446.91829622556, 5448.891022179534, 3642.562892452923, 3652.4156135018534, 3700.759450635727, 3721.8511259549587, 3739.364308067635, 1085.8371244313337, 1094.7427354571669, 1096.6381094238882, 1111.2276967507585, 1118.5805323003874, 11337.206395552315, 11413.144014198968, 11516.654500365003, 11523.193376463567, 11569.36368499732, 4577.931331030038, 4630.652701047435, 4836.032793441255, 4846.854594362175, 4868.9269660955115, 10008.299419040597, 10010.049045340827, 10015.0, 10023.849642255225, 10029.297949143636, 5377.919331210035, 5378.1663842104535, 5392.703401312965, 5395.73021348931, 5408.080626293539, 4417.293233082692, 4536.637069034469, 4796.136695394863, 4876.683015603974, 4899.804007839718, 3954.5056867891512, 4016.758994460342, 4074.9185016300103, 4115.773632450244, 4121.773302468333, 1302.6222395505388, 1326.6484381638872, 1329.5745361484232, 1333.5265980576949, 1342.3221273256943, 823.1521532821204, 847.1993965702352, 901.9774505637412, 910.3854897943861, 926.2358554192817, 5512.91730624041, 5752.511036561856, 5975.372585878507, 6064.151018857364, 6206.131141640174, 888.6045709659269, 895.8880139982502, 907.0, 923.690563661181, 947.4362754161263, 3505.3339865425487, 3798.6961043116485, 3846.076941534068, 3869.4389313549377, 3917.6679928824337, 10401.21990850674, 10891.782164356668, 10892.836607450894, 10896.782064358827, 10899.727506812234, 10848.511816968256, 10875.564977400974, 10875.67372978796, 10879.347239165676, 10924.126069914388, 6497.642629655415, 8214.26071653554, 8256.27542910838, 8261.256486916087, 8262.752117436487, 5833.504152147115, 5969.194158788508, 6009.158717779602, 6023.969880150571, 6062.544989202648, 9622.326437149331, 9686.95156524241, 9925.454100024568, 9965.850512242467, 9999.200063994718, 10049.346792458467, 10235.692929212135, 10252.487375631039, 10302.072813446826, 10315.381077135096, 6253.5622506424115, 7268.636568171464, 7382.9630851845395, 7393.741219057307, 7513.511621744788, 8308.169183081885, 8474.703385381483, 8485.51503486875, 8497.192766687154, 8510.829783404173, 7358.448116391185, 7438.1818000018875, 7477.140128885248, 7536.1333446654335, 7621.504602200839, 7198.992141100145, 7251.456140789356, 7287.890681639886, 7446.404287657083, 7453.366463850417, 6055.424734650202, 6877.449804015567, 6998.825029374103, 7006.614636194854, 7026.086608740831, 4901.754912254301, 4929.5809856161195, 4975.154223781893, 5001.599872010303, 5068.113080210875, 4788.11419887322, 4881.51184881509, 4963.305137280711, 5042.344495215599, 5168.560672342743], "value": 4605.368901534678, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.5233446138181, 282.9957550636739, 302.89852899278736, 305.12581454133914, 305.7294294549323, 4825.78283977221, 5207.609429292804, 5261.8421447356595, 5285.524302812746, 5303.310569625944, 4282.486101667804, 4838.975805120978, 5190.506901844319, 5213.843584692456, 5266.683998960056, 3476.6349533299017, 3802.2965751335955, 3926.0, 3950.9802450987704, 3951.9012024699323, 3431.5882094148737, 3962.5734735495344, 3964.762114273138, 3967.444557761916, 3977.8011099445057, 3311.503274508819, 3440.552728145338, 3506.824658767064, 3803.1442925341853, 3947.54603220629, 3590.2281009582935, 3907.5701672815967, 3919.2945269851416, 3932.1742434088874, 3967.3652215645648, 2985.8954936577115, 3246.9609724888046, 3344.3645707315777, 3363.4786608075788, 3365.831708414594, 5098.949010509921, 5123.565401687533, 5139.049275883974, 5151.356080489939, 5162.631902545829, 4249.596288352602, 4440.136112207608, 4463.66522510813, 4485.529019452975, 4506.098780243964, 4406.140802543506, 4959.6528243023, 5094.694318340874, 5454.53636440903, 5543.889122217533, 3291.670832916713, 3567.6789088981855, 3695.9630403695887, 3753.9812300938593, 3754.755940863835, 5435.934344948933, 5699.658020518741, 5742.875136199591, 5781.682007489587, 5791.536677065867, 3320.7841490303535, 3322.770574887259, 3751.643593858579, 3754.3805272129794, 3757.3800322946954, 3233.951490727641, 3386.695197432243, 3443.6900678939014, 3638.5809534281652, 3820.726667632754, 3976.9602303977167, 4249.043965107888, 4458.5987261146, 4472.932906006413, 4488.663350248745, 4913.484084171182, 5203.0, 5512.972435137879, 5515.007298686195, 5544.417836127229, 1277.208130958793, 1296.4814074370327, 1300.219868079145, 1304.302198323892, 1304.3869381390732, 729.712058217247, 736.5784036808903, 745.314310834034, 790.6007466229518, 799.9520028798174, 3837.0, 4085.55058943516, 4220.704550681484, 4382.729008587474, 4394.780260986938, 4734.644901632391, 4739.739314337745, 4751.64362672801, 4764.76176191196, 4805.342156955845, 3903.8048097595006, 4328.523862375137, 4342.6742994275555, 4360.4767427908855, 4381.759003254788, 2224.0103154096273, 2358.386819426927, 2487.4900645367657, 2494.3888747257038, 2516.282859385103, 4515.732632662102, 4656.930146047812, 4747.385888797825, 4921.26181072842, 4931.630127740434, 4674.555917187861, 4849.781759820746, 4879.4388645305535, 4906.062105468392, 5553.305836770403, 5018.473060328686, 5445.591580631469, 5500.669959802424, 5616.66300022, 5951.404859514014, 5467.753951072291, 5655.038643430545, 5667.291588551431, 5684.550439637866, 5710.685912274865, 4724.913269947862, 5628.240187574625, 5655.7454914528125, 5669.149627555899, 5690.231818704422, 4518.548145185455, 5525.950069486785, 5626.43735626434, 5914.2311499504785, 5985.970070149621, 3540.8760693375607, 3628.564572251347, 3817.370133927871, 3820.808959552067, 3839.9232015360094, 5455.809046683347, 5527.585431092603, 5743.396943320891, 5759.337676167294, 5763.697398393404, 5484.890302194013, 5489.533389661923, 5491.478309560585, 5500.862478438071, 5536.4186760389575, 5504.862378440571, 5516.641418307958, 5521.0, 5603.55171586264, 5634.971825140848, 4569.0, 5271.683698978073, 5380.650257733235, 5432.809851655173, 5434.402215756185, 4622.907541849211, 5517.641353312022, 5524.66851988882, 5525.723713814374, 5527.917081243865, 4844.006978569455, 5032.396112466381, 5458.781648734006, 5472.0, 5520.488177878975], "value": 3873.5438102597327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.9568625882446, 721.9675863515171, 738.3982054625479, 756.5498528375618, 816.3794220552028, 15081.245937703112, 16098.59753506163, 16111.838881611166, 16128.99999999997, 16156.515304540868, 12377.814332785016, 14589.197594132345, 14692.0, 14714.11715297083, 14761.92619036906, 677.6645560447561, 701.8701540215077, 707.9646017699121, 861.4099341950783, 878.7627340618029, 685.4379408884716, 686.5057158845623, 851.2296371783515, 878.2315473960285, 883.0507204754882, 917.8485549884261, 929.4051806843626, 937.5312343828086, 938.352536749644, 943.3632298198722, 907.577976241051, 907.8592818113203, 908.3641450984337, 908.981820363589, 909.6543313540845, 541.7291354322839, 675.787127054976, 676.256118269904, 677.0453660338899, 677.6543962579071, 14483.855161448357, 14982.925085374613, 15023.474178403703, 15045.0, 15050.698986020328, 14245.857541424557, 14491.492797752027, 14673.633159170999, 14884.181370024644, 14967.401303947829, 11825.408729563487, 11827.516221593476, 11877.703057423547, 11924.224925379822, 12183.756324873453, 3821.808909554511, 3836.0, 3847.153626202231, 3853.691704663648, 3986.86045988389, 115100.84899150985, 116995.0, 118088.22867656992, 119598.0, 119701.0, 11081.591274557713, 11183.44082795857, 11200.83198752003, 11346.48940797666, 11659.88340116605, 23896.283111506684, 23898.761012389652, 23904.0, 23919.52160956771, 23921.401964950666, 7774.300312971746, 8149.918500814916, 8211.630476628567, 8225.218604232588, 8451.323894088458, 4329.567043295709, 4381.430414046217, 4387.341898715218, 4448.354988526646, 4548.340490628906, 3539.0, 3695.7043436525014, 3722.6091260417807, 3729.2541491701227, 3737.588865224823, 676.9153855768029, 682.8143480464818, 684.0696652552524, 686.807693845724, 691.7025678958096, 535.7053620508705, 606.154414591651, 630.8580569371854, 638.256431257589, 660.4089340040637, 8112.107668156498, 8204.958975205205, 9488.383255088396, 9545.713628591153, 9653.72507869876, 641.3169973977699, 659.5317324699421, 673.3367632881608, 682.1119618176414, 684.0935760117864, 4297.033167537279, 4614.76926153691, 4621.79201935907, 4661.067786442725, 4666.230072038075, 88023.67964480538, 125870.0, 162291.18854405652, 162609.0, 166566.16716916338, 145480.27259863634, 146202.26898865437, 146298.0, 147688.2615586915, 148704.72019756667, 84800.0, 101348.0, 104152.99999999849, 121974.0, 122928.0, 8551.765261248924, 8979.281657467256, 9189.862152067859, 9631.229501639711, 9826.606935722491, 58347.12479312898, 60634.787304252786, 60997.69501152466, 61430.0785488227, 64775.352246476934, 80821.0, 84419.15580844358, 88427.55786221028, 91651.0, 93359.53320233355, 12833.871661283267, 12911.741765164456, 13014.674633134247, 13368.465261389825, 13444.932775336061, 14383.0, 14482.782758258423, 14526.0, 14625.561233163022, 14630.487932922297, 13346.19922804635, 13474.663133421744, 13665.658358541115, 13721.45114195421, 14225.110299255257, 13757.0, 13765.38055787472, 13914.234717090658, 14120.858791411954, 14400.351984160947, 12826.935865320615, 12903.806442903177, 12933.482660693468, 12999.675008124872, 13222.140560863512, 10684.0, 11012.614558490415, 11144.832827507431, 11273.943630281796, 11304.095672346357, 9234.144395691901, 10698.572057117628, 10974.78899430175, 11131.554737810397, 11280.717982050515], "value": 7864.306798727885, "unit": "score"}], "name": "dromaeo_css", "value": 7359.376949256145, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 117.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 123.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 129.0, 162.0, 109.0, 128.0, 127.0, 107.0, 158.0, 106.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 184.0, 184.0, 184.0, 219.0, 256.0, 183.0, 185.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 75.0, 155.0, 70.0, 57.0, 57.0, 59.0, 139.0, 56.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 135.0, 69.0, 70.0, 91.0, 114.0, 68.0, 71.0, 115.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 84.0, 87.0, 91.0, 134.0, 83.0, 91.0, 84.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 85.0, 86.0, 86.0, 86.0, 84.0, 85.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 50.0, 52.0, 54.0, 55.0, 53.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 73.0, 72.0, 75.0, 69.0, 69.0, 70.0, 71.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 90.0, 83.0, 86.0, 85.0, 85.0, 86.0, 91.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 142.0, 148.0, 139.0, 139.0, 141.0, 138.0, 245.0, 138.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 169.0, 166.0, 167.0, 170.0, 168.0, 166.0, 168.0, 169.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 67.0, 65.0, 67.0, 68.0, 67.0, 67.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1476.9, "alertThreshold": 2.0}]} 01:56:42 INFO - Return code: 0 01:56:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:56:42 INFO - # TBPL SUCCESS # 01:56:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:56:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:56:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:56:42 INFO - 2016-09-07 01:56:42.746 screenresolution[1798:9902] starting screenresolution argv=screenresolution get 01:56:42 INFO - 2016-09-07 01:56:42.749 screenresolution[1798:9902] Display 0: 1600x1200x32@60 01:56:42 INFO - 2016-09-07 01:56:42.755 screenresolution[1799:9905] starting screenresolution argv=screenresolution list 01:56:42 INFO - Available Modes on Display 0 01:56:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:56:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:56:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:56:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:56:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:56:42 INFO - Intel Iris: 01:56:42 INFO - Chipset Model: Intel Iris 01:56:42 INFO - Type: GPU 01:56:42 INFO - Bus: Built-In 01:56:42 INFO - VRAM (Dynamic, Max): 1536 MB 01:56:42 INFO - Vendor: Intel (0x8086) 01:56:42 INFO - Device ID: 0x0a2e 01:56:42 INFO - Revision ID: 0x0009 01:56:42 INFO - Displays: 01:56:42 INFO - Display: 01:56:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:56:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:56:42 INFO - Display Serial Number: 1600x1200 60 01:56:42 INFO - Main Display: Yes 01:56:42 INFO - Mirror: Off 01:56:42 INFO - Online: Yes 01:56:42 INFO - Rotation: Supported 01:56:42 INFO - Return code: 0 01:56:42 INFO - Running post-action listener: _resource_record_post_action 01:56:42 INFO - [mozharness: 2016-09-07 08:56:42.987129Z] Finished run-tests step (success) 01:56:42 INFO - Running post-run listener: _resource_record_post_run 01:56:43 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37463040; Write bytes: 706463744; Read time: 235; Write time: 2017 01:56:43 INFO - TinderboxPrint: CPU usage
54.0% 01:56:43 INFO - TinderboxPrint: I/O read bytes / time
37,463,040 / 235 01:56:43 INFO - TinderboxPrint: I/O write bytes / time
706,463,744 / 2,017 01:56:43 INFO - TinderboxPrint: CPU idle
2,229.0 (46.0%) 01:56:43 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 01:56:43 INFO - TinderboxPrint: CPU user
1,642.2 (33.9%) 01:56:43 INFO - TinderboxPrint: Swap in / out
442,421,248 / 0 01:56:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 385772032; Write bytes: 380143616; Read time: 28018; Write time: 790 01:56:43 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36500480; Write bytes: 297951232; Read time: 223; Write time: 1159 01:56:43 INFO - Running post-run listener: _upload_blobber_files 01:56:43 INFO - Blob upload gear active. 01:56:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:56:43 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 01:56:43 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:56:43 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:56:43 INFO - (blobuploader) - INFO - Open directory for files ... 01:56:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:56:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:44 INFO - (blobuploader) - INFO - Done attempting. 01:56:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:56:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:56:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:45 INFO - (blobuploader) - INFO - Done attempting. 01:56:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:56:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:56:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:48 INFO - (blobuploader) - INFO - Done attempting. 01:56:48 INFO - (blobuploader) - INFO - Iteration through files over. 01:56:48 INFO - Return code: 0 01:56:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:56:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:56:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/801c869e19033b3bc1958a50b1d81a1b52372cdc15d4a2c3e8a1bda101e01802a82f267bfe2f70b65648ce50a863d106c751ffd50d6880b5984e8ad18a278757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4db40886843821b296d4383ff99a4d96d3675c2831ba0f45fb68a891bae84d8ae63ed602a4df1b05068446b2ef2098d5c5b2627c058d94d7944d7fd2e07d9489", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494eeb569c1b95382680e6cb5fcdf07cd5fc1d1d39088124981f2ae0f66fa5a59826471835d27126385498cec83d156802412aa607369a23a1c573f3de3c45c4"} 01:56:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:56:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:56:48 INFO - Contents: 01:56:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/801c869e19033b3bc1958a50b1d81a1b52372cdc15d4a2c3e8a1bda101e01802a82f267bfe2f70b65648ce50a863d106c751ffd50d6880b5984e8ad18a278757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4db40886843821b296d4383ff99a4d96d3675c2831ba0f45fb68a891bae84d8ae63ed602a4df1b05068446b2ef2098d5c5b2627c058d94d7944d7fd2e07d9489", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494eeb569c1b95382680e6cb5fcdf07cd5fc1d1d39088124981f2ae0f66fa5a59826471835d27126385498cec83d156802412aa607369a23a1c573f3de3c45c4"} 01:56:48 INFO - Running post-run listener: copy_logs_to_upload_dir 01:56:48 INFO - Copying logs to upload dir... 01:56:48 INFO - mkdir: /builds/slave/test/build/upload/logs 01:56:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.552642 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-07 01:56:48.228638) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 01:56:48.232222) ========= 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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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/Autoland/sha512/801c869e19033b3bc1958a50b1d81a1b52372cdc15d4a2c3e8a1bda101e01802a82f267bfe2f70b65648ce50a863d106c751ffd50d6880b5984e8ad18a278757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4db40886843821b296d4383ff99a4d96d3675c2831ba0f45fb68a891bae84d8ae63ed602a4df1b05068446b2ef2098d5c5b2627c058d94d7944d7fd2e07d9489", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494eeb569c1b95382680e6cb5fcdf07cd5fc1d1d39088124981f2ae0f66fa5a59826471835d27126385498cec83d156802412aa607369a23a1c573f3de3c45c4"} build_url:https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011348 build_url: 'https://queue.taskcluster.net/v1/task/Fq_69_MwQsO3bRUTg40x0g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/801c869e19033b3bc1958a50b1d81a1b52372cdc15d4a2c3e8a1bda101e01802a82f267bfe2f70b65648ce50a863d106c751ffd50d6880b5984e8ad18a278757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4db40886843821b296d4383ff99a4d96d3675c2831ba0f45fb68a891bae84d8ae63ed602a4df1b05068446b2ef2098d5c5b2627c058d94d7944d7fd2e07d9489", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494eeb569c1b95382680e6cb5fcdf07cd5fc1d1d39088124981f2ae0f66fa5a59826471835d27126385498cec83d156802412aa607369a23a1c573f3de3c45c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 01:56:48.266698) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 01:56:48.267550) ========= 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.TEVcK1Trdc/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.pAhrhFZU3m/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.005172 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 01:56:48.320032) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 01:56:48.320645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 01:56:48.321336) ========= ========= Total master_lag: 0.19 =========