builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0251 starttime: 1475782106.67 results: success (0) buildid: 20161006094701 builduid: e195d20f67e74b48b49034b2f7f2a19f revision: ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.674139) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.674584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.674888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.707147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.707443) ========= 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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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-10-06 12:28:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-10-06 12:28:26 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.200985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.930503) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.930826) ========= 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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.027485 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:26.982311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-06 12:28:26.983088) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2 --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 ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CWvZIdNPA5/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.nUYhpm8tw4/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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-10-06 12:28:27,062 truncating revision to first 12 chars 2016-10-06 12:28:27,062 Setting DEBUG logging. 2016-10-06 12:28:27,063 attempt 1/10 2016-10-06 12:28:27,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/ed4bc0d84b0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-06 12:28:29,049 unpacking tar archive at: autoland-ed4bc0d84b0b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.267702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-06 12:28:29.274367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:29.274691) ========= script_repo_revision: ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:29.275141) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:29.275424) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 12:28:29.294333) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-06 12:28:29.294734) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:28:29 INFO - MultiFileLogger online at 20161006 12:28:29 in /builds/slave/test 12:28:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 12:28:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:28:29 INFO - {'append_to_log': False, 12:28:29 INFO - 'base_work_dir': '/builds/slave/test', 12:28:29 INFO - 'blob_upload_branch': 'Autoland', 12:28:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:28:29 INFO - 'branch': 'Autoland', 12:28:29 INFO - 'buildbot_json_path': 'buildprops.json', 12:28:29 INFO - 'config_files': ('talos/mac_config.py',), 12:28:29 INFO - 'default_actions': ('clobber', 12:28:29 INFO - 'read-buildbot-config', 12:28:29 INFO - 'download-and-extract', 12:28:29 INFO - 'populate-webroot', 12:28:29 INFO - 'create-virtualenv', 12:28:29 INFO - 'install', 12:28:29 INFO - 'run-tests'), 12:28:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:28:29 INFO - 'download_minidump_stackwalk': True, 12:28:29 INFO - 'download_symbols': 'ondemand', 12:28:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:28:29 INFO - 'tooltool.py': '/tools/tooltool.py', 12:28:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:28:29 INFO - '/tools/misc-python/virtualenv.py')}, 12:28:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:28:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:28:29 INFO - 'installer_path': 'installer.exe', 12:28:29 INFO - 'log_level': 'info', 12:28:29 INFO - 'log_name': 'talos', 12:28:29 INFO - 'log_to_console': True, 12:28:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:28:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:28:29 INFO - 'opt_config_files': (), 12:28:29 INFO - 'pip_index': False, 12:28:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:28:29 INFO - 'cmd': ('bash', 12:28:29 INFO - '-c', 12:28:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:28:29 INFO - 'enabled': True, 12:28:29 INFO - 'halt_on_failure': False, 12:28:29 INFO - 'name': 'check_screen_resolution'},), 12:28:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:28:29 INFO - 'cmd': ('bash', 12:28:29 INFO - '-c', 12:28:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:28:29 INFO - 'enabled': True, 12:28:29 INFO - 'halt_on_failure': False, 12:28:29 INFO - 'name': 'check_screen_resolution'},), 12:28:29 INFO - 'run_cmd_checks_enabled': True, 12:28:29 INFO - 'sps_profile': False, 12:28:29 INFO - 'sps_profile_interval': 0, 12:28:29 INFO - 'suite': 'dromaeojs-e10s', 12:28:29 INFO - 'system_bits': '32', 12:28:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:28:29 INFO - 'title': 't-yosemite-r7-0251', 12:28:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:28:29 INFO - 'use_talos_json': True, 12:28:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:28:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:28:29 INFO - 'work_dir': 'build'} 12:28:29 INFO - [mozharness: 2016-10-06 19:28:29.512974Z] Running clobber step. 12:28:29 INFO - Running pre-action listener: _resource_record_pre_action 12:28:29 INFO - Running main action method: clobber 12:28:29 INFO - rmtree: /builds/slave/test/build 12:28:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:28:31 INFO - Running post-action listener: _resource_record_post_action 12:28:31 INFO - [mozharness: 2016-10-06 19:28:31.745708Z] Finished clobber step (success) 12:28:31 INFO - [mozharness: 2016-10-06 19:28:31.745985Z] Running read-buildbot-config step. 12:28:31 INFO - Running pre-action listener: _resource_record_pre_action 12:28:31 INFO - Running main action method: read_buildbot_config 12:28:31 INFO - Using buildbot properties: 12:28:31 INFO - { 12:28:31 INFO - "project": "", 12:28:31 INFO - "product": "firefox", 12:28:31 INFO - "script_repo_revision": "production", 12:28:31 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 12:28:31 INFO - "repository": "", 12:28:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 12:28:31 INFO - "buildid": "20161006094701", 12:28:31 INFO - "pgo_build": "False", 12:28:31 INFO - "basedir": "/builds/slave/test", 12:28:31 INFO - "buildnumber": 218, 12:28:31 INFO - "slavename": "t-yosemite-r7-0251", 12:28:31 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:28:31 INFO - "platform": "yosemite_r7", 12:28:31 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 12:28:31 INFO - "branch": "autoland", 12:28:31 INFO - "repo_path": "integration/autoland", 12:28:31 INFO - "revision": "ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2", 12:28:31 INFO - "stage_platform": "macosx64", 12:28:31 INFO - "builduid": "e195d20f67e74b48b49034b2f7f2a19f", 12:28:31 INFO - "slavebuilddir": "test" 12:28:31 INFO - } 12:28:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:28:31 INFO - Running post-action listener: _resource_record_post_action 12:28:31 INFO - [mozharness: 2016-10-06 19:28:31.751472Z] Finished read-buildbot-config step (success) 12:28:31 INFO - [mozharness: 2016-10-06 19:28:31.751670Z] Running download-and-extract step. 12:28:31 INFO - Running pre-action listener: _resource_record_pre_action 12:28:31 INFO - Running main action method: download_and_extract 12:28:31 INFO - mkdir: /builds/slave/test/build/tests 12:28: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')]} 12:28:31 INFO - trying https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:28:31 INFO - Downloading https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:28:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 12:28:33 INFO - Downloaded 1199 bytes. 12:28:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:28:33 INFO - Using the following test package requirements: 12:28:33 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:28:33 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:28:33 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:28:33 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'jsshell-mac.zip'], 12:28:33 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:28:33 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:28:33 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:28:33 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:28:33 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:28:33 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:28:33 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:28:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:28:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:28:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:28:33 INFO - Fetch https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:28:33 INFO - Http code: 200 12:28:33 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 12:28:33 INFO - x-amz-cf-id: hFLhWkClcqUFM7rNdvOIX2fTQnbF_bcqgtU_Wpc6fnaY0xfSRHgzXQ== 12:28:33 INFO - x-amz-version-id: TGbbH2YxYv6fF_ux6tmSxDcJUqBZCNiC 12:28:34 INFO - Expected file size: 16478189 12:28:34 INFO - Obtained file size: 16478189 12:28:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:28:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:28:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:28:40 INFO - Fetch https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:28:41 INFO - Http code: 200 12:28:41 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 12:28:41 INFO - x-amz-cf-id: 6sIpnj0FVWpcXiCsGgQoRic3WtzgVCTo7Z0UrS2_Xk1zBro-2H_xuA== 12:28:41 INFO - x-amz-version-id: TGbbH2YxYv6fF_ux6tmSxDcJUqBZCNiC 12:28:41 INFO - Expected file size: 16478189 12:28:41 INFO - Obtained file size: 16478189 12:28:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:28:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:28:47 INFO - Fetch https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:28:48 INFO - Http code: 200 12:28:48 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 12:28:48 INFO - x-amz-cf-id: NKAJI6h7IajOogZziyPy-QinFLyil6RpVAQ6YnaccuT3wJDGbXNbpQ== 12:28:48 INFO - x-amz-version-id: OckNwrT0LToNANhI0ZW3Ul7rSZsITwJ3 12:28:48 INFO - Expected file size: 14409006 12:28:48 INFO - Obtained file size: 14409006 12:28:49 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')]} 12:28:49 INFO - trying https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:28:49 INFO - Downloading https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 12:28:52 INFO - Downloaded 111671698 bytes. 12:28:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:28:52 INFO - mkdir: /builds/slave/test/properties 12:28:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:28:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:28:52 INFO - Contents: 12:28:52 INFO - build_url:https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:28:53 INFO - Running post-action listener: _resource_record_post_action 12:28:53 INFO - Running post-action listener: set_extra_try_arguments 12:28:53 INFO - [mozharness: 2016-10-06 19:28:53.106784Z] Finished download-and-extract step (success) 12:28:53 INFO - [mozharness: 2016-10-06 19:28:53.106914Z] Running populate-webroot step. 12:28:53 INFO - Running pre-action listener: _resource_record_pre_action 12:28:53 INFO - Running main action method: populate_webroot 12:28:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:28:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:28:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:28:53 INFO - '--tppagecycles', 12:28:53 INFO - '7'], 12:28:53 INFO - 'tests': ['tsvgm']}}, 12:28:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:28:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:28:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:28:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:28:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['damp', 'tps']}, 12:28:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'tests': ['damp', 'tps']}, 12:28:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['dromaeo_dom']}, 12:28:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:28:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['basic_compositor_video']}, 12:28:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:28:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['a11yr', 12:28:53 INFO - 'ts_paint', 12:28:53 INFO - 'tpaint', 12:28:53 INFO - 'sessionrestore', 12:28:53 INFO - 'sessionrestore_no_auto_restore', 12:28:53 INFO - 'tabpaint']}, 12:28:53 INFO - 'other-e10s': {'tests': ['a11yr', 12:28:53 INFO - 'ts_paint', 12:28:53 INFO - 'tpaint', 12:28:53 INFO - 'sessionrestore', 12:28:53 INFO - 'sessionrestore_no_auto_restore', 12:28:53 INFO - 'tabpaint']}, 12:28:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:28:53 INFO - 'ts_paint', 12:28:53 INFO - 'tpaint', 12:28:53 INFO - 'sessionrestore', 12:28:53 INFO - 'sessionrestore_no_auto_restore', 12:28:53 INFO - 'tabpaint']}, 12:28:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:28:53 INFO - 'ts_paint', 12:28:53 INFO - 'tpaint', 12:28:53 INFO - 'sessionrestore', 12:28:53 INFO - 'sessionrestore_no_auto_restore', 12:28:53 INFO - 'tabpaint']}, 12:28:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['a11yr', 12:28:53 INFO - 'ts_paint', 12:28:53 INFO - 'tpaint', 12:28:53 INFO - 'sessionrestore', 12:28:53 INFO - 'sessionrestore_no_auto_restore', 12:28:53 INFO - 'tabpaint']}, 12:28:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['a11yr', 12:28:53 INFO - 'ts_paint', 12:28:53 INFO - 'tpaint', 12:28:53 INFO - 'sessionrestore', 12:28:53 INFO - 'sessionrestore_no_auto_restore', 12:28:53 INFO - 'tabpaint']}, 12:28:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['tsvgx', 12:28:53 INFO - 'tsvgr_opacity', 12:28:53 INFO - 'tart', 12:28:53 INFO - 'tscrollx', 12:28:53 INFO - 'cart']}, 12:28:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:28:53 INFO - 'tsvgr_opacity', 12:28:53 INFO - 'tart', 12:28:53 INFO - 'tscrollx', 12:28:53 INFO - 'cart']}, 12:28:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'talos_options': ['--disable-e10s'], 12:28:53 INFO - 'tests': ['tp5o']}, 12:28:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'tests': ['tp5o']}, 12:28:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'talos_options': ['--disable-e10s', 12:28:53 INFO - '--xperf_path', 12:28:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:53 INFO - 'tests': ['tp5n']}, 12:28:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:53 INFO - 'talos_options': ['--xperf_path', 12:28:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:53 INFO - 'tests': ['tp5n']}}, 12:28:53 INFO - 'talos.zip': {'path': '', 12:28:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:28:53 INFO - Running post-action listener: _resource_record_post_action 12:28:53 INFO - [mozharness: 2016-10-06 19:28:53.125590Z] Finished populate-webroot step (success) 12:28:53 INFO - [mozharness: 2016-10-06 19:28:53.125712Z] Running create-virtualenv step. 12:28:53 INFO - Running pre-action listener: _resource_record_pre_action 12:28:53 INFO - Running main action method: create_virtualenv 12:28:53 INFO - Creating virtualenv /builds/slave/test/build/venv 12:28:53 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 12:28:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:28:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:28:53 INFO - Using real prefix '/tools/python27' 12:28:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:28:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:28:56 INFO - Installing pip.................done. 12:28:56 INFO - Return code: 0 12:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:28:57 INFO - Reading from file tmpfile_stdout 12:28:57 INFO - Output received: 12:28:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:28:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:28: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')]} 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28: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')]} 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:57 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:28:57 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 12:28:57 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 12:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:28:57 INFO - 'HOME': '/Users/cltbld', 12:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:28:57 INFO - 'LOGNAME': 'cltbld', 12:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:57 INFO - 'MOZ_NO_REMOTE': '1', 12:28:57 INFO - 'NO_EM_RESTART': '1', 12:28:57 INFO - 'PAGER': '/bin/cat', 12:28:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:57 INFO - 'PWD': '/builds/slave/test', 12:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:28:57 INFO - 'SHELL': '/bin/bash', 12:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:28:57 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:28:57 INFO - 'USER': 'cltbld', 12:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:57 INFO - 'XPC_FLAGS': '0x0', 12:28:57 INFO - 'XPC_SERVICE_NAME': '0', 12:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:57 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 12:28:57 INFO - Cleaning up... 12:28:57 INFO - Return code: 0 12:28:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:28: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')]} 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28: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')]} 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:57 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:28:57 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 12:28:57 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 12:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:28:57 INFO - 'HOME': '/Users/cltbld', 12:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:28:57 INFO - 'LOGNAME': 'cltbld', 12:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:57 INFO - 'MOZ_NO_REMOTE': '1', 12:28:57 INFO - 'NO_EM_RESTART': '1', 12:28:57 INFO - 'PAGER': '/bin/cat', 12:28:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:57 INFO - 'PWD': '/builds/slave/test', 12:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:28:57 INFO - 'SHELL': '/bin/bash', 12:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:28:57 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:28:57 INFO - 'USER': 'cltbld', 12:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:57 INFO - 'XPC_FLAGS': '0x0', 12:28:57 INFO - 'XPC_SERVICE_NAME': '0', 12:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:57 INFO - Downloading/unpacking psutil>=3.1.1 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:29:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:00 INFO - Installing collected packages: psutil 12:29:00 INFO - Running setup.py install for psutil 12:29:01 INFO - building 'psutil._psutil_osx' extension 12:29:01 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 12:29:01 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 12:29:01 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 12:29:01 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 12:29:01 INFO - building 'psutil._psutil_posix' extension 12:29:01 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 12:29:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:29:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:29:01 INFO - ^ 12:29:01 INFO - 1 warning generated. 12:29:01 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 12:29:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:02 INFO - Successfully installed psutil 12:29:02 INFO - Cleaning up... 12:29:02 INFO - Return code: 0 12:29:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:29:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:02 INFO - 'HOME': '/Users/cltbld', 12:29:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:02 INFO - 'LOGNAME': 'cltbld', 12:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:02 INFO - 'MOZ_NO_REMOTE': '1', 12:29:02 INFO - 'NO_EM_RESTART': '1', 12:29:02 INFO - 'PAGER': '/bin/cat', 12:29:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:02 INFO - 'PWD': '/builds/slave/test', 12:29:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:02 INFO - 'SHELL': '/bin/bash', 12:29:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:02 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:02 INFO - 'USER': 'cltbld', 12:29:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:02 INFO - 'XPC_FLAGS': '0x0', 12:29:02 INFO - 'XPC_SERVICE_NAME': '0', 12:29:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:29:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:29:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:29:04 INFO - Installing collected packages: mozsystemmonitor 12:29:04 INFO - Running setup.py install for mozsystemmonitor 12:29:05 INFO - Successfully installed mozsystemmonitor 12:29:05 INFO - Cleaning up... 12:29:05 INFO - Return code: 0 12:29:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:29:05 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')]} 12:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:05 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')]} 12:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:05 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:05 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 12:29:05 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 12:29:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:05 INFO - 'HOME': '/Users/cltbld', 12:29:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:05 INFO - 'LOGNAME': 'cltbld', 12:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:05 INFO - 'MOZ_NO_REMOTE': '1', 12:29:05 INFO - 'NO_EM_RESTART': '1', 12:29:05 INFO - 'PAGER': '/bin/cat', 12:29:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:05 INFO - 'PWD': '/builds/slave/test', 12:29:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:05 INFO - 'SHELL': '/bin/bash', 12:29:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:05 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:05 INFO - 'USER': 'cltbld', 12:29:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:05 INFO - 'XPC_FLAGS': '0x0', 12:29:05 INFO - 'XPC_SERVICE_NAME': '0', 12:29:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:05 INFO - Downloading/unpacking jsonschema==2.5.1 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:29:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 INFO - Downloading functools32-3.2.3-2.tar.gz 12:29:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:29:08 INFO - warning: no files found matching '*.txt' 12:29:08 INFO - no previously-included directories found matching 'build' 12:29:08 INFO - no previously-included directories found matching 'dist' 12:29:08 INFO - no previously-included directories found matching '.git*' 12:29:08 INFO - Installing collected packages: jsonschema, functools32 12:29:08 INFO - Running setup.py install for functools32 12:29:08 INFO - warning: no files found matching '*.txt' 12:29:08 INFO - no previously-included directories found matching 'build' 12:29:08 INFO - no previously-included directories found matching 'dist' 12:29:08 INFO - no previously-included directories found matching '.git*' 12:29:08 INFO - Successfully installed jsonschema functools32 12:29:08 INFO - Cleaning up... 12:29:08 INFO - Return code: 0 12:29:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:08 INFO - 'HOME': '/Users/cltbld', 12:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:08 INFO - 'LOGNAME': 'cltbld', 12:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:08 INFO - 'MOZ_NO_REMOTE': '1', 12:29:08 INFO - 'NO_EM_RESTART': '1', 12:29:08 INFO - 'PAGER': '/bin/cat', 12:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:08 INFO - 'PWD': '/builds/slave/test', 12:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:08 INFO - 'SHELL': '/bin/bash', 12:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:08 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:08 INFO - 'USER': 'cltbld', 12:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:08 INFO - 'XPC_FLAGS': '0x0', 12:29:08 INFO - 'XPC_SERVICE_NAME': '0', 12:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:29:09 INFO - Cleaning up... 12:29:09 INFO - Return code: 0 12:29:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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')]} 12:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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', '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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:29: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 blobuploader==1.2.4 12:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:09 INFO - 'HOME': '/Users/cltbld', 12:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:09 INFO - 'LOGNAME': 'cltbld', 12:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:09 INFO - 'MOZ_NO_REMOTE': '1', 12:29:09 INFO - 'NO_EM_RESTART': '1', 12:29:09 INFO - 'PAGER': '/bin/cat', 12:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:09 INFO - 'PWD': '/builds/slave/test', 12:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:09 INFO - 'SHELL': '/bin/bash', 12:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:09 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:09 INFO - 'USER': 'cltbld', 12:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:09 INFO - 'XPC_FLAGS': '0x0', 12:29:09 INFO - 'XPC_SERVICE_NAME': '0', 12:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:09 INFO - Downloading/unpacking blobuploader==1.2.4 12:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:11 INFO - Downloading blobuploader-1.2.4.tar.gz 12:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:29:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:29:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:12 INFO - Downloading docopt-0.6.1.tar.gz 12:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:29:12 INFO - Installing collected packages: blobuploader, requests, docopt 12:29:12 INFO - Running setup.py install for blobuploader 12:29:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:29:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:29:13 INFO - Running setup.py install for requests 12:29:13 INFO - Running setup.py install for docopt 12:29:13 INFO - Successfully installed blobuploader requests docopt 12:29:13 INFO - Cleaning up... 12:29:13 INFO - Return code: 0 12:29:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:13 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')]} 12:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:13 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')]} 12:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:13 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:13 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 12:29:13 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 12:29:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:13 INFO - 'HOME': '/Users/cltbld', 12:29:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:13 INFO - 'LOGNAME': 'cltbld', 12:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:13 INFO - 'MOZ_NO_REMOTE': '1', 12:29:13 INFO - 'NO_EM_RESTART': '1', 12:29:13 INFO - 'PAGER': '/bin/cat', 12:29:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:13 INFO - 'PWD': '/builds/slave/test', 12:29:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:13 INFO - 'SHELL': '/bin/bash', 12:29:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:13 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:13 INFO - 'USER': 'cltbld', 12:29:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:13 INFO - 'XPC_FLAGS': '0x0', 12:29:13 INFO - 'XPC_SERVICE_NAME': '0', 12:29:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-y4CIwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-lLnL96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Y181Lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-hjKnqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-o7gyre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-IFupz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-B33r5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-GdDhtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:14 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-RKQfCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Pv0njn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-wassEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Yu7EhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-0pIVyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-sq94nG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Utcx0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ZuouG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:15 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-gsiJNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:15 INFO - Running setup.py install for manifestparser 12:29:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:16 INFO - Running setup.py install for mozcrash 12:29:16 INFO - Running setup.py install for mozdebug 12:29:16 INFO - Running setup.py install for mozdevice 12:29:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:29:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:16 INFO - Running setup.py install for mozfile 12:29:16 INFO - Running setup.py install for mozhttpd 12:29:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:16 INFO - Running setup.py install for mozinfo 12:29:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:16 INFO - Running setup.py install for mozInstall 12:29:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Running setup.py install for mozleak 12:29:17 INFO - Running setup.py install for mozlog 12:29:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Running setup.py install for moznetwork 12:29:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Running setup.py install for mozprocess 12:29:17 INFO - Running setup.py install for mozprofile 12:29:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Running setup.py install for mozrunner 12:29:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Running setup.py install for mozscreenshot 12:29:18 INFO - Running setup.py install for moztest 12:29:18 INFO - Running setup.py install for mozversion 12:29:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:18 INFO - Cleaning up... 12:29:18 INFO - Return code: 0 12:29:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:18 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')]} 12:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:18 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')]} 12:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:18 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:18 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 12:29:18 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 12:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:18 INFO - 'HOME': '/Users/cltbld', 12:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:18 INFO - 'LOGNAME': 'cltbld', 12:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:18 INFO - 'MOZ_NO_REMOTE': '1', 12:29:18 INFO - 'NO_EM_RESTART': '1', 12:29:18 INFO - 'PAGER': '/bin/cat', 12:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:18 INFO - 'PWD': '/builds/slave/test', 12:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:18 INFO - 'SHELL': '/bin/bash', 12:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:18 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:18 INFO - 'USER': 'cltbld', 12:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:18 INFO - 'XPC_FLAGS': '0x0', 12:29:18 INFO - 'XPC_SERVICE_NAME': '0', 12:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:18 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-rJX692-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:18 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)) 12:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:18 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Zc6tcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:18 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)) 12:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:18 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-cHFuln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-LI95HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-EvISrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-LcXy8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-x5ZbIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Kai5pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-MjW7tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jc0ah3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-5zaCDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:19 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)) 12:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:19 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-voC4UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-LxE7E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-w68VlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-2hKa1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-dTPPdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:20 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-E0W47o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:20 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)) 12:29:20 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)) 12:29:20 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)) 12:29:20 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)) 12:29:20 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)) 12:29:20 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)) 12:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29:23 INFO - Downloading blessings-1.6.tar.gz 12:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:23 INFO - Installing collected packages: blessings 12:29:23 INFO - Running setup.py install for blessings 12:29:23 INFO - Successfully installed blessings 12:29:23 INFO - Cleaning up... 12:29:23 INFO - Return code: 0 12:29:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:23 INFO - Reading from file tmpfile_stdout 12:29:23 INFO - Current package versions: 12:29:23 INFO - blessings == 1.6 12:29:23 INFO - blobuploader == 1.2.4 12:29:23 INFO - docopt == 0.6.1 12:29:23 INFO - functools32 == 3.2.3-2 12:29:23 INFO - jsonschema == 2.5.1 12:29:23 INFO - manifestparser == 1.1 12:29:23 INFO - mozInstall == 1.12 12:29:23 INFO - mozcrash == 0.17 12:29:23 INFO - mozdebug == 0.1 12:29:23 INFO - mozdevice == 0.48 12:29:23 INFO - mozfile == 1.2 12:29:23 INFO - mozhttpd == 0.7 12:29:23 INFO - mozinfo == 0.9 12:29:23 INFO - mozleak == 0.1 12:29:23 INFO - mozlog == 3.4 12:29:23 INFO - moznetwork == 0.27 12:29:23 INFO - mozprocess == 0.23 12:29:23 INFO - mozprofile == 0.28 12:29:23 INFO - mozrunner == 6.12 12:29:23 INFO - mozscreenshot == 0.1 12:29:23 INFO - mozsystemmonitor == 0.3 12:29:23 INFO - moztest == 0.7 12:29:23 INFO - mozversion == 1.4 12:29:23 INFO - psutil == 3.1.1 12:29:23 INFO - requests == 1.2.3 12:29:23 INFO - wsgiref == 0.1.2 12:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:23 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')]} 12:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:24 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:24 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 12:29:24 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 12:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:24 INFO - 'HOME': '/Users/cltbld', 12:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:24 INFO - 'LOGNAME': 'cltbld', 12:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:24 INFO - 'MOZ_NO_REMOTE': '1', 12:29:24 INFO - 'NO_EM_RESTART': '1', 12:29:24 INFO - 'PAGER': '/bin/cat', 12:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:24 INFO - 'PWD': '/builds/slave/test', 12:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:24 INFO - 'SHELL': '/bin/bash', 12:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:24 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:24 INFO - 'USER': 'cltbld', 12:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:24 INFO - 'XPC_FLAGS': '0x0', 12:29:24 INFO - 'XPC_SERVICE_NAME': '0', 12:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 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)) 12:29:24 INFO - Cleaning up... 12:29:24 INFO - Return code: 0 12:29:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:25 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 0x10ad1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c9ca1170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, '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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 12:29:25 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 12:29:25 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 12:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:25 INFO - 'HOME': '/Users/cltbld', 12:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:25 INFO - 'LOGNAME': 'cltbld', 12:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:25 INFO - 'MOZ_NO_REMOTE': '1', 12:29:25 INFO - 'NO_EM_RESTART': '1', 12:29:25 INFO - 'PAGER': '/bin/cat', 12:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:25 INFO - 'PWD': '/builds/slave/test', 12:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:25 INFO - 'SHELL': '/bin/bash', 12:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:29:25 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:25 INFO - 'USER': 'cltbld', 12:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:25 INFO - 'XPC_FLAGS': '0x0', 12:29:25 INFO - 'XPC_SERVICE_NAME': '0', 12:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:29:25 INFO - Cleaning up... 12:29:25 INFO - Return code: 0 12:29:25 INFO - Running post-action listener: _resource_record_post_action 12:29:25 INFO - Running post-action listener: _start_resource_monitoring 12:29:25 INFO - Starting resource monitoring. 12:29:25 INFO - [mozharness: 2016-10-06 19:29:25.393069Z] Finished create-virtualenv step (success) 12:29:25 INFO - [mozharness: 2016-10-06 19:29:25.394044Z] Running install step. 12:29:25 INFO - Running pre-action listener: _resource_record_pre_action 12:29:25 INFO - Running main action method: install 12:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:25 INFO - Reading from file tmpfile_stdout 12:29:25 INFO - Detecting whether we're running mozinstall >=1.0... 12:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:29:25 INFO - Reading from file tmpfile_stdout 12:29:25 INFO - Output received: 12:29:25 INFO - Usage: mozinstall [options] installer 12:29:25 INFO - Options: 12:29:25 INFO - -h, --help show this help message and exit 12:29:25 INFO - -d DEST, --destination=DEST 12:29:25 INFO - Directory to install application into. [default: 12:29:25 INFO - "/builds/slave/test"] 12:29:25 INFO - --app=APP Application being installed. [default: firefox] 12:29:25 INFO - mkdir: /builds/slave/test/build/application 12:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:29:59 INFO - Reading from file tmpfile_stdout 12:29:59 INFO - Output received: 12:29:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:29:59 INFO - Running post-action listener: _resource_record_post_action 12:29:59 INFO - [mozharness: 2016-10-06 19:29:59.861079Z] Finished install step (success) 12:29:59 INFO - [mozharness: 2016-10-06 19:29:59.861216Z] Running run-tests step. 12:29:59 INFO - Running pre-action listener: _resource_record_pre_action 12:29:59 INFO - Running main action method: run_tests 12:29:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:59 INFO - 2016-10-06 12:29:59.875 screenresolution[1646:7652] starting screenresolution argv=screenresolution get 12:29:59 INFO - 2016-10-06 12:29:59.878 screenresolution[1646:7652] Display 0: 1600x1200x32@60 12:29:59 INFO - 2016-10-06 12:29:59.883 screenresolution[1647:7654] starting screenresolution argv=screenresolution list 12:29:59 INFO - Available Modes on Display 0 12:29:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:30:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:30:00 INFO - Intel Iris: 12:30:00 INFO - Chipset Model: Intel Iris 12:30:00 INFO - Type: GPU 12:30:00 INFO - Bus: Built-In 12:30:00 INFO - VRAM (Dynamic, Max): 1536 MB 12:30:00 INFO - Vendor: Intel (0x8086) 12:30:00 INFO - Device ID: 0x0a2e 12:30:00 INFO - Revision ID: 0x0009 12:30:00 INFO - Displays: 12:30:00 INFO - Display: 12:30:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:30:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:30:00 INFO - Display Serial Number: 1600x1200 60 12:30:00 INFO - Main Display: Yes 12:30:00 INFO - Mirror: Off 12:30:00 INFO - Online: Yes 12:30:00 INFO - Rotation: Supported 12:30:00 INFO - Return code: 0 12:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:00 INFO - Python 2.7.3 12:30:00 INFO - Return code: 0 12:30:00 INFO - grabbing minidump binary from tooltool 12:30:00 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')]} 12:30:00 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 0x10ab32d30>, '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 0x10a958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab2ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:00 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 12:30:00 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 12:30:00 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 12:30:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:00 INFO - Return code: 0 12:30:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:30:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:30:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CWvZIdNPA5/Render', 12:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:00 INFO - 'HOME': '/Users/cltbld', 12:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:00 INFO - 'LOGNAME': 'cltbld', 12:30:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:00 INFO - 'MOZ_NO_REMOTE': '1', 12:30:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:00 INFO - 'NO_EM_RESTART': '1', 12:30:00 INFO - 'PAGER': '/bin/cat', 12:30:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:00 INFO - 'PWD': '/builds/slave/test', 12:30:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:00 INFO - 'SHELL': '/bin/bash', 12:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUYhpm8tw4/Listeners', 12:30:00 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 12:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:00 INFO - 'USER': 'cltbld', 12:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:00 INFO - 'XPC_FLAGS': '0x0', 12:30:00 INFO - 'XPC_SERVICE_NAME': '0', 12:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:30:00 INFO - mozversion application_buildid: 20161006094701 12:30:00 INFO - mozversion application_changeset: ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2 12:30:00 INFO - mozversion application_display_name: Nightly 12:30:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:00 INFO - mozversion application_name: Firefox 12:30:00 INFO - mozversion application_remotingname: firefox 12:30:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:30:00 INFO - mozversion application_vendor: Mozilla 12:30:00 INFO - mozversion application_version: 52.0a1 12:30:00 INFO - mozversion platform_buildid: 20161006094701 12:30:00 INFO - mozversion platform_changeset: ed4bc0d84b0bb24f2c528b3e489ee727d763b0d2 12:30:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:30:00 INFO - mozversion platform_version: 52.0a1 12:30:00 INFO - using testdate: 1475782200 12:30:00 INFO - actual date: 1475782200 12:30:00 INFO - starting webserver on 'localhost:49273' 12:30:00 INFO - SUITE-START | Running 2 tests 12:30:00 INFO - TEST-START | dromaeo_css 12:30:00 INFO - operating with platform_type : mac_ 12:30:00 INFO - Initialising browser for dromaeo_css test... 12:30:00 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpfWu4Wo/profile http://localhost:49273/getInfo.html) 12:30:03 INFO - PROCESS | 1657 | 2016-10-06 12:30:03.061 plugin-container[1660:7824] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c3b, name = 'com.apple.tsm.portname' 12:30:03 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:03 INFO - PROCESS | 1657 | 2016-10-06 12:30:03.065 plugin-container[1660:7824] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 12:30:03 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:03 INFO - PROCESS | 1657 | 2016-10-06 12:30:03.065 plugin-container[1660:7824] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:30:03 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 12:30:03 INFO - PROCESS | 1657 | colorDepth:24 12:30:03 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 12:30:03 INFO - PROCESS | 1657 | __metrics 12:30:03 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 12:30:03 INFO - TEST-INFO | 1657: exit 0 12:30:03 INFO - Browser initialized. 12:30:03 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:03 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpfWu4Wo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:06 INFO - PROCESS | 1661 | 2016-10-06 12:30:06.603 plugin-container[1662:7943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.tsm.portname' 12:30:06 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:06 INFO - PROCESS | 1661 | 2016-10-06 12:30:06.639 plugin-container[1662:7943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 12:30:06 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:06 INFO - PROCESS | 1661 | 2016-10-06 12:30:06.639 plugin-container[1662:7943] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:30:16 INFO - PROCESS | 1661 | RSS: Main: 240136192 12:30:16 INFO - PROCESS | 1661 | 12:31:05 INFO - PROCESS | 1661 | 1475782265827 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] 12:33:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 12:33:21 INFO - PROCESS | 1661 | RSS: Main: 251654144 12:33:21 INFO - PROCESS | 1661 | 12:36:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 12:36:24 INFO - PROCESS | 1661 | RSS: Main: 265576448 12:36:24 INFO - PROCESS | 1661 | 12:39:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 12:39:26 INFO - PROCESS | 1661 | RSS: Main: 270974976 12:39:26 INFO - PROCESS | 1661 | 12:42:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 12:42:29 INFO - PROCESS | 1661 | RSS: Main: 271003648 12:42:29 INFO - PROCESS | 1661 | 12:45:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 12:45:32 INFO - PROCESS | 1661 | RSS: Main: 276267008 12:45:32 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 12:48:35 INFO - PROCESS | 1661 | RSS: Main: 278949888 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | __start_tp_report 12:48:35 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 12:48:35 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 12:48:35 INFO - PROCESS | 1661 | |i|pagename|runs| 12:48:35 INFO - PROCESS | 1661 | |0;dojo.html;3132.2012886713883;3324.883629072983;3388.390089783839;3391.6608339166087;3394.168428734959;5224.4775522447735;5350.2777125088105;5609.439056094394;5740.110282906146;5772.278465191851;5537.141743029827;5644.689542075184;5721.914171287423;5782.335031471372;5820.417958204188;4281.67887408444;5192.61055420845;5256.526912577867;5295.9672863791575;5304.70824104676;5219.45195754446;5278.551323137519;5365.382980957201;5398.91901621476;5413.052715774744;5326.307580014612;5338.679679219259;5340.732963351817;5345.118055520854;5353.518183363496;5132.538071573539;5179.404368497633;5231.058409486291;5263.894722105575;5315.574754019669;5167.121589329785;5201.167813149916;5213.556847667953;5227.451117632631;5239.423663397023;4563.06457176278;5543.722813859331;5777.815547812688;5788.18965344852;5809.157672137517;4937.580305674022;6033.48715359195;6054.72753726079;6057.3336932937345;6124.724387402578;6186.505079593621;6188.783392581283;6195.132681424604;6198;6202.255729312511;5711.600187986801;5718.25662663851;5733.971330143406;5736.971315143398;5741.598088133832;6125.571219981323;6141.785037523665;6168.938310616926;6193.287771906198;6222.253329600477;6118.9846826261555;6121.756825504462;6126.426068710427;6141.7543298267565;6158.753649853956;6126;6152.138700581922;6153.2000839890525;6187.3503282154725;6202.162708034448;6085.786997455157;6121.727351053096;6132.78385080153;6174.691265436711;6175.752969881244;6145;6243.0635404688755;6260.780872669526;6260.874782504325;6264.248290205204;4943.629227807929;4954.355933728592;4958.727270000112;4963.982383611351;4973.751312434364;4344.196323680066;4407.184670835905;4415.470143582791;4446.911061778746;4471.530489298641;5752.597318187771;5961.791337303173;5981.641101533922;5989.311229208697;6008.969955150196;6177.227846519185;6227.906581401283;6232.906506402408;6249.187605611241;6272.2159730033745;4422.29243321067;5171.65536960393;5327.200919862052;5336.706481143497;5505.559555235572;5366.436524164985;5387.030334539742;5438.483344082306;5438.91841622376;5473.808416705476;5105.412877519133;5606.803761868315;5952.5833191676165;6234.937650623436;6464.385883341075;4866.780994855169;6217.937820621736;6230.532710046855;6233.065040244;6236.501079913687;5271.947280527145;5739.7417116229035;6210.975189093749;6271.184745983175;6350.047492876106;5064.366954130734;6196.535259855439;6224.004159334485;6228.626282423069;6235.345288744798;5009.624278179078;6204.224471941007;6211.565190436626;6260.499160067093;6292.772909282646;6038.909416358846;6092.360302168386;6093.847653808691;6104;6144;6102.825161501705;6130.7241174146375;6142.232220972379;6148.877022459436;6156.353582873913;4854.878628034327;5896.7936122235515;5899.292084949662;6003.909941350796;6050.45545900871;6085.178500902499;6090.594617129203;6104.298005729398;6115.970584430425;6155.84610384744;5924.259467566554;6033.5173186145885;6045.546584006129;6070.666113363631;6081.604695694766 12:48:35 INFO - PROCESS | 1661 | |1;ext.html;505.6612069913159;508.3899320815021;519.000923222796;531.3544043986556;558.8938101760667;38228.23543529131;40656.39015414764;40806.775796726106;40836.79581602088;40934.79532602336;41263.79368103163;41741.582584174146;41786;41826.7908660457;42038.36942445858;9694.563744631509;9755.95122024389;9812.362196457207;9872.753181170492;9890.505474726271;9782.90217097827;9879.703608891708;9908.256880733938;9912.306138570302;9921.950390248076;9855.655052073178;9869.654562090329;9879.950600246988;9896.208303335716;9910.950445247763;9690.612375504972;9807.65673201438;9837.213022958145;9856.112949834549;9879.357841740264;9484.19384352331;9529.857052144223;9531.523423828781;9612.759681007961;9625.951870240604;34492.655073449445;35793.64206357929;36436.635633643855;39806.80096599527;40042;3239.2873567815045;3312.893842707379;3353.396388650042;3396.252824378633;3440.7591468597207;30653.38693226123;30860;31236.37527249465;31725.36549269025;32035.839820800746;3243.221626809572;3261.706446419834;3273.427150248721;3356.026752241847;3361.5629968104236;477393;481395;481668;482810;485005;60984;61507;61522;61543.38456615466;61618.383816161266;41744.582554174674;43277.35083973743;43360.783196083816;43910.34134487985;43983.340249896275;16284.185790710417;16481.917590411973;16482.505524834272;16519;16521.586960325847;10566.841497377545;12624.17942833713;12739.108262421541;12773.361331933365;12815.16701414405;17852.0459546278;18645.160967756485;18681.346152884584;18730;18891;6471.288158302583;6485.578437401648;7014.018037474758;7355.080614923135;7915.604219789103;811.5658122904218;899.5786657081795;993.4734590666958;998.8651532043226;1056.1920131099714;22913.541729165656;29607;30012;30306;30347.937822176118;1017.3692310767372;1053.4258828938248;1085.5006696919347;1113.8273567597034;1137.0789660375124;15039.323230454656;15496.14771187573;15625.687486250212;15659.608509787346;15805.68388632221;631715;634814;635226;636553;637206.4653857877;56573.71713141408;56837;57044.144337834965;57263;57526.7123664379;1334.7463981843418;1360.9115407498482;1386.3553447646893;1387;1389.610908945497;28966.841326346712;28976.275593109498;29136.7086329134;29384.559231611114;29642.85178574093;48024.55926322076;48519.272210917574;48671.75664121657;48702.512974869795;48857.5114248853;593777;600228;601587;602221;603254.9023529302;25968.480630386908;26325.73674263233;26397;26659.466810664067;26706.59940100939;45898.77050614726;46244.76877615591;46876.7656161717;46958;47594;38679.41980870233;38700.612993870825;38823.77030759134;39842.40236396514;39904.2019159621;38154.61845381511;38211.853644390714;38632.227355452174;39161.82514524569;39498.80250598729;31996;32374.35251294914;37052.62947370492;37691.62308376881;37698.81150594229;27756.861215693792;31821.522677160323;32647.020589382355;34454;34535.48196777;25125.623115652914;25469.217154799728;25544.103092165384;25679.10123145681;26174 12:48:35 INFO - PROCESS | 1661 | |2;jquery.html;363.8035460851141;366.98348574314156;373.3391896343471;375.57559957248304;393.7204584744831;14053.718925621493;18837.246510139594;19957.301494447696;20345.796542034594;20619.484512887186;19112.235510579594;19222.51943701408;19763.505912352197;19786.70319945202;19847.801521984777;5610.186522954179;5675.091985282355;5910.822675319747;5918.4969277611235;5929.525637948954;4898.804047838083;5643.5767317451355;5846.269216347957;5876.265466816648;5887.911681324784;5548.223248745179;5758.395368486312;5788.6816225107605;5798.768049278045;5810.215620891189;5649.067903795869;5753.453421924932;5775.768969241225;5792.739326730307;5806.042003069489;4999.4250661174065;5059.241113832954;5060.924086138711;5079.568236699885;5362.249285100089;12760.553380631725;13044.08691391603;13048.739025219538;13056.281904495248;19653.606927861507;2103.82117520011;2104.726385569866;2145.7854214578574;2194.5501172259646;2458.5451691437142;8185.263326300602;8894.911050889537;9427.90572094284;9641.951790241073;10180.185585153169;1369.233229391544;1712.2808420463434;1800.4508624869409;1862.9567442232258;1868.8691791574595;119964;121537;122106;122446;128896.35551822274;9587.616495340248;11066.336019838835;11115.444227788577;11206.43967801623;11315.717107072225;11071.612493562687;14032.36854341557;14148.504802331869;14150.85849141516;14190.716185676229;8152.673893044212;8304.086550479442;8318.45930014547;8442.409031367868;8470.872936905951;8107.473014254173;8263.049749278789;8404.033536143423;8427.705030323908;8441.15588441151;4439.933400998988;4443.600075993176;4462.888427789267;4574.016586433932;4604.194266003436;1915.9521011974812;1942.3201879342116;1959.0950514673225;1962.313190383383;1969.8522610804248;798.8681867491799;826.8222332198607;864.7232885476587;923.3490389275545;954.5370495309769;14764.818814494814;19038.667293289338;19760.70358944617;20367.388978330673;20899.79100209009;828.0973738624928;892.4288455388556;892.834825557274;992.3004281981185;1002.4336250018716;9617.509286060671;10412.895871041193;10541.525631346607;10639.840402393971;10740.677779666623;528897;541485;548184;553714;598812;127695.08457373147;128432;129807;131546;133581;917.9082091790901;923.6490133749132;951.752544338477;955.3694561589317;958.4105774948357;13765.518206862711;17879.910600446914;17959.73060404121;17999.730004050212;18046.81953180504;45571.77214113908;50106.49893501018;53726;53878.19182712184;55250.44749552453;54882.72558637341;78696;79615.20384796077;79884.20115798767;80638;7690.548856259479;7855.60721963911;7883.684652614061;8058.556779377191;8112.918870811216;10190.949045254727;11721.648350549498;12009.219400738924;12064.51741930348;12077.456514457046;10184.338018028804;10650.254482186172;10708.143348531943;10960.342379457257;10980.945095274792;9965.551550180115;10239.897601023895;10348.896511035095;10583.4708264588;10667.839982400115;8757.868631970397;9795.314327997257;10909.127269818555;10937;11212.551497940318;7829.530228186327;7875.094364147967;7981.920180798118;8076.232757887991;8131.340896596437;7614.5811980339395;8929.508877011827;9968.800623987625;10468.214883883587;10678.89321106779 12:48:35 INFO - PROCESS | 1661 | |3;mootools.html;1502.3389708528248;1544.1815837606068;1754.2895127473378;1828.5154434074964;1877.9436616901498;7912.7626171214815;8040.115587285394;8269.421140520159;8825.205731484166;8847.29221662267;8382.11987741286;8386.790330241747;8422.115677853813;8570.657173713043;8711.3902026858;2247.550489902018;2287.4281429642588;2289.713785776778;2290.7823756743082;2297.6208925527267;2102.4954011037316;2119.565489074736;2276.203328834911;2302.458922153293;2318.5826551220775;2266.5240299537118;2270.4664403865077;2278.214016164429;2280.680704701343;2285.6914316567218;2243.383069655841;2255.9548809023727;2264.0377839418184;2275.306031660343;2283.098176220392;2238.1159442020476;2251.133313674231;2253.5943530164564;2264.399934017487;2271.5229801741652;8718.389712720113;8740.08229135938;8828.24959878411;8830.911690883073;8863.025067242597;5069.239614057884;5223.817166399158;5230.476952304777;5233.21501774733;5239.292695486099;2617.6858776946697;2882.8126171798976;3071.769617278713;3393.4400823864034;3531.876384326536;1026.650938680852;1054.8470471781625;1067.3702515515806;1086.581666058573;1090.9727256818564;10986.395748233845;11004.834927476095;11107.000369966741;11241.550337986471;11278.323300601907;4281.97859010703;4368.934465982949;4418.293073108352;4477.462704475419;4482.7758612069265;8739;8881.555922203865;8888.422252553562;8902.910970890338;8911;5030.874228144253;5043.596512279082;5047.369078865142;5049.495050494995;5055.9241611375855;4317.460317460317;4334.0248444099825;4684.625229981593;4749.311349854252;4769.952300476951;3612.313660404567;3625.220577575834;3680.7179548679455;3704.111013356829;3724.404095344732;1063.6277302944063;1110.21729680575;1139.3904261220373;1142.4402042999036;1150.1431433581868;772.6098320348189;800.139849661616;879.2789912272003;906.6201221792794;934.2245935873344;4771.66598338113;4819.831305904277;4850.660453768202;5593.049181639131;5670.971645141748;426.2498003513776;426.88474111989586;428.87133859841924;433.14886248521805;964.8793900762405;3769.8878830744998;3844.1927195288745;3901.9609803902167;4017.7991100445447;4032.052467670095;9817.803643927082;10479;10561.89438105609;10599;10607.416592087355;10522.842157367646;10545.420001899816;10572.629957951434;10578.788424231472;10636.734081647866;7900.2889739923685;8041.678332866737;8045.5574943377505;8060.4760690556095;8065.8790118149445;5533.750981205775;5675.404082571271;5727.828165155052;5771.134329850556;5833.912491312549;9702.757431063998;9794.82462104552;9800.460974646463;9827.508624568885;9841.311108222355;9625.56684949165;9914.702558923243;10042.397456152654;10056.798864022825;10086.79826403453;5941.584089113893;6414.069959855888;6583.144191255106;6866.828329291808;6952.165740111017;7967.9203207967175;8246.422750407413;8254.504729716236;8262.380321476034;8262.628181731956;7179.569225846466;7308.5614863108385;7356.595387253752;7425.257474252531;7427.962860185665;7126.679299431435;7149.642517874189;7176.96411517939;7195.640217989185;7215.6031418270395;6737.629430381376;6846.931530684828;6868.793936181923;6870.003849441903;6885.311468853075;4919.557239848431;4920.803167873388;5004.449510553909;5012.724300163526;5038.571721403575;4901.485344038824;4904;5006.6495345325475;5061.8734531637;5122.820701275437 12:48:35 INFO - PROCESS | 1661 | |4;prototype.html;266.18281874644833;292.34514687100886;306.0710742895312;312.1151535397149;314.37439495404124;5092.847214583564;5140.408852981908;5226;5379.488948549882;5562.9443705562935;4410.162069206848;5196.778756992104;5247.711375874325;5305.206777154334;5469.507744303012;4055.6147166019327;4172;4176.6241038306625;4183.142455796555;4204.285271503852;3669.4128939369707;3883.3980732986433;3884.6892248620043;4198.496180458334;4223.239816832969;3504.4918486819397;4107.77407242603;4109.773962432065;4138.193052354792;4174.70777045607;4069.308217603;4121.5878412158845;4134.594237959093;4171.457710497646;4192.853250136231;3315.983420082908;3580.6598373154507;3601.891943241707;3604.6575575320303;3633.818309084562;5118.795248190068;5180;5286.682799032032;5408.675479471244;5418.126039881504;4100.462326627515;4159.792010399498;4535.863924082283;4738.597219236369;4741.193997020515;4823;5768.673205162794;5779.537636989031;5789.536837053026;5852.619579727304;3260.522003277526;3782.224643947984;3946.802659867024;3957.6438120569;3967.4725230786357;5325.813596524103;5985.700714964234;6088.786892458743;6216.073017364677;6268.780592679234;3987.940180897289;4006.5392479864604;4009.3384591542654;4017.779022153751;4020.597940205956;3890.7665540067687;3973.2252210819192;4020.296448121567;4061.593840615915;4095.2219078375015;4180.519240287345;4207.789610519461;4457.331400289982;4498.775061246924;4726.976365118221;5889.823305300848;5907.734151963172;5913.70431478433;5934;5953.732082056318;1282.7819270723999;1311.6196303072193;1336.432016393029;1372.025861638248;1405.8242719660043;711.4130842055325;759.7037155509356;833.1377024779446;836.4521238588734;842.5408152556871;4327.2643650579475;4452.844150454784;4562.581831629242;4562.657800664963;4635.559621835988;4719.787609557578;4863.902721945612;4898.978546989293;5010.749462526859;5083.38999320084;3697.44707222967;3815.980920095382;3976.701747368959;3997.8206429103484;4262.850800221977;2587.8059145564157;2678.6606696651675;3009.495252373813;3127.781055326105;3179.3467396953456;4718.504557021532;5876.529877609866;6278.843028924222;6337.7781777637565;6344.714487848058;5261.789528418897;5490.615656904059;5546.389897111315;6168.766246750578;6308.274548426898;5147.716875571805;5565.805196818092;6303.681977845191;6315.084647518568;6325.240971083499;5069.340985671839;6037.426444487766;6044.214252147192;6107.838876149646;6138.846528836815;5127.923081153711;5676.262085928803;5919.24128178601;5971.552133589911;5985.610935289193;6221.720022598904;6229.813105606839;6268.592541484789;6287.74849006053;6385.584936979081;3441.8451169696923;3562.9845494034075;3903.902402439962;3925.509311336083;3936.8386326033315;4482.394876691605;4852.733099679552;5058.013687330935;5834.38738932406;5884.382139875252;5323.387810401853;5701.486866181897;5719.256496655408;5891.675957822361;6023.3073196582955;4809.158397280392;5932.6737029462065;5935.554833387427;5954.940450595439;5961.198236828684;4521.728696278103;5015.871428928462;5448;5689.947359738461;5749.683767392834;4936.703797772001;5857.414258574108;5917.467427931507;5949.8215053548465;5960.5827592068135;4694.43666759991;5829.59192856513;5841.32824725162;5850.6782126981725;5857.891159182605 12:48:35 INFO - PROCESS | 1661 | |5;yui.html;591.7248479457052;677.5392732941599;696.5542053086023;710.8720430322539;765.6516285090285;15025.32386042628;17108.74336884946;17725.025123618212;17729;18240.72638910414;16131.354745810182;16695.83304166958;16749.246283917222;16801.411950581733;16848.83151168488;700.2157583506454;852.1308265569115;883.3198437203349;885.3979294080023;885.6280362247871;703.1772825793834;855.9315254779602;870.8650159225331;886.2865393358344;888.3825741109899;910.3491003932187;920.7606022434148;921.2123634292684;921.8525035994209;922.9769255768626;867.3624885708999;873.9038705742363;894.5214310343968;916.9633214671405;922.7785331520421;659.8977158540386;688.900109484127;690.0201713566747;691.1602403080232;691.87892118879;15458.381664733397;15467;15467;15472.226388680521;15516;12369.62891113268;13295.268760218185;13844.377003034888;13901.235432051233;14166.85833141676;11448.599299024578;11598.246114002563;11651.883481165165;11926.582569610022;12182.512699492097;3738.1962877981364;3765.4540091686554;3802.657760801541;3914.9608503914883;3924.2543916656027;108821.45589272083;111141.44429277883;115070.42464787622;116128;118207.81792182055;10400;10534.20993425496;10592.629257975934;10681.359118452918;10688.198385121146;20289.391318260477;21029.789702102782;21144;21276.468088297916;21850.89074554617;7876.803079922933;8018.3184429324165;8100.2304781045705;8161.55111468863;8224.136465671018;3660.213054193361;3881.029742564359;4362.236608593484;4388.824447022147;4426;2961.822290662524;3516.4549494828343;3628.6552777486095;3638.0904773806546;3698.519192505011;567.3418834152363;664.4850241063215;676.1683129750336;686.2382755141789;688.421725750372;513.2422443944613;603.5684485592734;648.3840351665842;670.9499633074223;677.3294438505844;7900.130985591581;9222;9320.72037838866;9686.273529485317;9849.950750246202;521.6817741177879;530.8593222795962;532.0263917031823;672.5292295393244;678.1556961582762;4351.43431353922;4392.472903251631;4417.2269852775635;4469.10617876426;4561.201789686792;155053;157423;160859.19570402073;162800;162892;130780.3460982689;132471;135973;140036.29981850027;142350;118991.40504297423;119342.403287983;119939.40030299792;121446;124407.75592244143;8811;8895.06601806801;8971.32715046361;8997.415168014059;9074.682386116454;56229.87540249254;64020;65741;66569.66715166393;66984;92416;95158.52420737852;98943.50528247312;100260.48046396404;101483.49258253661;11322.490487927898;11545.59590414365;11634.76730465403;11791.233569817934;11978.940105299418;12311.19977201479;12745.426455809325;12922.870771292166;13091.672708182372;13382.531611393944;11130.721731956766;12046.939765301116;12067.637970860887;12077.456514456695;12109.394530273627;11943.462544185706;12134.150609457252;12159.696007599881;12231.819726871256;12343.321117338273;11801.881981180422;12019.579314723942;12123;12132.393380330772;12341.136120471481;10273.486325683836;10318.870674679994;10400.913182831051;10443.164546836082;10733.248672593156;8946;10485.842712359168;10557.20820938448;10579.15366770641;10647.361158330525 12:48:35 INFO - PROCESS | 1661 | __end_tp_report 12:48:35 INFO - PROCESS | 1661 | __start_cc_report 12:48:35 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,352 12:48:35 INFO - PROCESS | 1661 | __end_cc_report 12:48:35 INFO - PROCESS | 1661 | __startTimestamp1475783315593__endTimestamp 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | ------- Summary: start ------- 12:48:35 INFO - PROCESS | 1661 | Number of tests: 6 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5662.51 Median:5924.26 stddev:658.36 (11.1%) stddev-sans-first:629.75 12:48:35 INFO - PROCESS | 1661 | Values: 3132.2 3324.9 3388.4 3391.7 3394.2 5224.5 5350.3 5609.4 5740.1 5772.3 5537.1 5644.7 5721.9 5782.3 5820.4 4281.7 5192.6 5256.5 5296.0 5304.7 5219.5 5278.6 5365.4 5398.9 5413.1 5326.3 5338.7 5340.7 5345.1 5353.5 5132.5 5179.4 5231.1 5263.9 5315.6 5167.1 5201.2 5213.6 5227.5 5239.4 4563.1 5543.7 5777.8 5788.2 5809.2 4937.6 6033.5 6054.7 6057.3 6124.7 6186.5 6188.8 6195.1 6198.0 6202.3 5711.6 5718.3 5734.0 5737.0 5741.6 6125.6 6141.8 6168.9 6193.3 6222.3 6119.0 6121.8 6126.4 6141.8 6158.8 6126.0 6152.1 6153.2 6187.4 6202.2 6085.8 6121.7 6132.8 6174.7 6175.8 6145.0 6243.1 6260.8 6260.9 6264.2 4943.6 4954.4 4958.7 4964.0 4973.8 4344.2 4407.2 4415.5 4446.9 4471.5 5752.6 5961.8 5981.6 5989.3 6009.0 6177.2 6227.9 6232.9 6249.2 6272.2 4422.3 5171.7 5327.2 5336.7 5505.6 5366.4 5387.0 5438.5 5438.9 5473.8 5105.4 5606.8 5952.6 6234.9 6464.4 4866.8 6217.9 6230.5 6233.1 6236.5 5271.9 5739.7 6211.0 6271.2 6350.0 5064.4 6196.5 6224.0 6228.6 6235.3 5009.6 6204.2 6211.6 6260.5 6292.8 6038.9 6092.4 6093.8 6104.0 6144.0 6102.8 6130.7 6142.2 6148.9 6156.4 4854.9 5896.8 5899.3 6003.9 6050.5 6085.2 6090.6 6104.3 6116.0 6155.8 5924.3 6033.5 6045.5 6070.7 6081.6 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:69706.34 Median:39973.10 stddev:154071.78 (385.4%) stddev-sans-first:154416.38 12:48:35 INFO - PROCESS | 1661 | Values: 505.7 508.4 519.0 531.4 558.9 38228.2 40656.4 40806.8 40836.8 40934.8 41263.8 41741.6 41786.0 41826.8 42038.4 9694.6 9756.0 9812.4 9872.8 9890.5 9782.9 9879.7 9908.3 9912.3 9922.0 9855.7 9869.7 9880.0 9896.2 9911.0 9690.6 9807.7 9837.2 9856.1 9879.4 9484.2 9529.9 9531.5 9612.8 9626.0 34492.7 35793.6 36436.6 39806.8 40042.0 3239.3 3312.9 3353.4 3396.3 3440.8 30653.4 30860.0 31236.4 31725.4 32035.8 3243.2 3261.7 3273.4 3356.0 3361.6 477393.0 481395.0 481668.0 482810.0 485005.0 60984.0 61507.0 61522.0 61543.4 61618.4 41744.6 43277.4 43360.8 43910.3 43983.3 16284.2 16481.9 16482.5 16519.0 16521.6 10566.8 12624.2 12739.1 12773.4 12815.2 17852.0 18645.2 18681.3 18730.0 18891.0 6471.3 6485.6 7014.0 7355.1 7915.6 811.6 899.6 993.5 998.9 1056.2 22913.5 29607.0 30012.0 30306.0 30347.9 1017.4 1053.4 1085.5 1113.8 1137.1 15039.3 15496.1 15625.7 15659.6 15805.7 631715.0 634814.0 635226.0 636553.0 637206.5 56573.7 56837.0 57044.1 57263.0 57526.7 1334.7 1360.9 1386.4 1387.0 1389.6 28966.8 28976.3 29136.7 29384.6 29642.9 48024.6 48519.3 48671.8 48702.5 48857.5 593777.0 600228.0 601587.0 602221.0 603254.9 25968.5 26325.7 26397.0 26659.5 26706.6 45898.8 46244.8 46876.8 46958.0 47594.0 38679.4 38700.6 38823.8 39842.4 39904.2 38154.6 38211.9 38632.2 39161.8 39498.8 31996.0 32374.4 37052.6 37691.6 37698.8 27756.9 31821.5 32647.0 34454.0 34535.5 25125.6 25469.2 25544.1 25679.1 26174.0 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33145.87 Median:4453.24 stddev:93319.32 (2095.5%) stddev-sans-first:93548.64 12:48:35 INFO - PROCESS | 1661 | Values: 363.8 367.0 373.3 375.6 393.7 14053.7 18837.2 19957.3 20345.8 20619.5 19112.2 19222.5 19763.5 19786.7 19847.8 5610.2 5675.1 5910.8 5918.5 5929.5 4898.8 5643.6 5846.3 5876.3 5887.9 5548.2 5758.4 5788.7 5798.8 5810.2 5649.1 5753.5 5775.8 5792.7 5806.0 4999.4 5059.2 5060.9 5079.6 5362.2 12760.6 13044.1 13048.7 13056.3 19653.6 2103.8 2104.7 2145.8 2194.6 2458.5 8185.3 8894.9 9427.9 9642.0 10180.2 1369.2 1712.3 1800.5 1863.0 1868.9 119964.0 121537.0 122106.0 122446.0 128896.4 9587.6 11066.3 11115.4 11206.4 11315.7 11071.6 14032.4 14148.5 14150.9 14190.7 8152.7 8304.1 8318.5 8442.4 8470.9 8107.5 8263.0 8404.0 8427.7 8441.2 4439.9 4443.6 4462.9 4574.0 4604.2 1916.0 1942.3 1959.1 1962.3 1969.9 798.9 826.8 864.7 923.3 954.5 14764.8 19038.7 19760.7 20367.4 20899.8 828.1 892.4 892.8 992.3 1002.4 9617.5 10412.9 10541.5 10639.8 10740.7 528897.0 541485.0 548184.0 553714.0 598812.0 127695.1 128432.0 129807.0 131546.0 133581.0 917.9 923.6 951.8 955.4 958.4 13765.5 17879.9 17959.7 17999.7 18046.8 45571.8 50106.5 53726.0 53878.2 55250.4 54882.7 78696.0 79615.2 79884.2 80638.0 7690.5 7855.6 7883.7 8058.6 8112.9 10190.9 11721.6 12009.2 12064.5 12077.5 10184.3 10650.3 10708.1 10960.3 10980.9 9965.6 10239.9 10348.9 10583.5 10667.8 8757.9 9795.3 10909.1 10937.0 11212.6 7829.5 7875.1 7981.9 8076.2 8131.3 7614.6 8929.5 9968.8 10468.2 10678.9 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5495.15 Median:4911.78 stddev:3123.94 (63.6%) stddev-sans-first:3118.29 12:48:35 INFO - PROCESS | 1661 | Values: 1502.3 1544.2 1754.3 1828.5 1877.9 7912.8 8040.1 8269.4 8825.2 8847.3 8382.1 8386.8 8422.1 8570.7 8711.4 2247.6 2287.4 2289.7 2290.8 2297.6 2102.5 2119.6 2276.2 2302.5 2318.6 2266.5 2270.5 2278.2 2280.7 2285.7 2243.4 2256.0 2264.0 2275.3 2283.1 2238.1 2251.1 2253.6 2264.4 2271.5 8718.4 8740.1 8828.2 8830.9 8863.0 5069.2 5223.8 5230.5 5233.2 5239.3 2617.7 2882.8 3071.8 3393.4 3531.9 1026.7 1054.8 1067.4 1086.6 1091.0 10986.4 11004.8 11107.0 11241.6 11278.3 4282.0 4368.9 4418.3 4477.5 4482.8 8739.0 8881.6 8888.4 8902.9 8911.0 5030.9 5043.6 5047.4 5049.5 5055.9 4317.5 4334.0 4684.6 4749.3 4770.0 3612.3 3625.2 3680.7 3704.1 3724.4 1063.6 1110.2 1139.4 1142.4 1150.1 772.6 800.1 879.3 906.6 934.2 4771.7 4819.8 4850.7 5593.0 5671.0 426.2 426.9 428.9 433.1 964.9 3769.9 3844.2 3902.0 4017.8 4032.1 9817.8 10479.0 10561.9 10599.0 10607.4 10522.8 10545.4 10572.6 10578.8 10636.7 7900.3 8041.7 8045.6 8060.5 8065.9 5533.8 5675.4 5727.8 5771.1 5833.9 9702.8 9794.8 9800.5 9827.5 9841.3 9625.6 9914.7 10042.4 10056.8 10086.8 5941.6 6414.1 6583.1 6866.8 6952.2 7967.9 8246.4 8254.5 8262.4 8262.6 7179.6 7308.6 7356.6 7425.3 7428.0 7126.7 7149.6 7177.0 7195.6 7215.6 6737.6 6846.9 6868.8 6870.0 6885.3 4919.6 4920.8 5004.4 5012.7 5038.6 4901.5 4904.0 5006.6 5061.9 5122.8 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4581.95 Median:4917.84 stddev:1462.74 (29.7%) stddev-sans-first:1430.53 12:48:35 INFO - PROCESS | 1661 | Values: 266.2 292.3 306.1 312.1 314.4 5092.8 5140.4 5226.0 5379.5 5562.9 4410.2 5196.8 5247.7 5305.2 5469.5 4055.6 4172.0 4176.6 4183.1 4204.3 3669.4 3883.4 3884.7 4198.5 4223.2 3504.5 4107.8 4109.8 4138.2 4174.7 4069.3 4121.6 4134.6 4171.5 4192.9 3316.0 3580.7 3601.9 3604.7 3633.8 5118.8 5180.0 5286.7 5408.7 5418.1 4100.5 4159.8 4535.9 4738.6 4741.2 4823.0 5768.7 5779.5 5789.5 5852.6 3260.5 3782.2 3946.8 3957.6 3967.5 5325.8 5985.7 6088.8 6216.1 6268.8 3987.9 4006.5 4009.3 4017.8 4020.6 3890.8 3973.2 4020.3 4061.6 4095.2 4180.5 4207.8 4457.3 4498.8 4727.0 5889.8 5907.7 5913.7 5934.0 5953.7 1282.8 1311.6 1336.4 1372.0 1405.8 711.4 759.7 833.1 836.5 842.5 4327.3 4452.8 4562.6 4562.7 4635.6 4719.8 4863.9 4899.0 5010.7 5083.4 3697.4 3816.0 3976.7 3997.8 4262.9 2587.8 2678.7 3009.5 3127.8 3179.3 4718.5 5876.5 6278.8 6337.8 6344.7 5261.8 5490.6 5546.4 6168.8 6308.3 5147.7 5565.8 6303.7 6315.1 6325.2 5069.3 6037.4 6044.2 6107.8 6138.8 5127.9 5676.3 5919.2 5971.6 5985.6 6221.7 6229.8 6268.6 6287.7 6385.6 3441.8 3563.0 3903.9 3925.5 3936.8 4482.4 4852.7 5058.0 5834.4 5884.4 5323.4 5701.5 5719.3 5891.7 6023.3 4809.2 5932.7 5935.6 5954.9 5961.2 4521.7 5015.9 5448.0 5689.9 5749.7 4936.7 5857.4 5917.5 5949.8 5960.6 4694.4 5829.6 5841.3 5850.7 5857.9 12:48:35 INFO - PROCESS | 1661 | 12:48:35 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25801.73 Median:12406.36 stddev:42398.93 (341.8%) stddev-sans-first:42475.62 12:48:35 INFO - PROCESS | 1661 | Values: 591.7 677.5 696.6 710.9 765.7 15025.3 17108.7 17725.0 17729.0 18240.7 16131.4 16695.8 16749.2 16801.4 16848.8 700.2 852.1 883.3 885.4 885.6 703.2 855.9 870.9 886.3 888.4 910.3 920.8 921.2 921.9 923.0 867.4 873.9 894.5 917.0 922.8 659.9 688.9 690.0 691.2 691.9 15458.4 15467.0 15467.0 15472.2 15516.0 12369.6 13295.3 13844.4 13901.2 14166.9 11448.6 11598.2 11651.9 11926.6 12182.5 3738.2 3765.5 3802.7 3915.0 3924.3 108821.5 111141.4 115070.4 116128.0 118207.8 10400.0 10534.2 10592.6 10681.4 10688.2 20289.4 21029.8 21144.0 21276.5 21850.9 7876.8 8018.3 8100.2 8161.6 8224.1 3660.2 3881.0 4362.2 4388.8 4426.0 2961.8 3516.5 3628.7 3638.1 3698.5 567.3 664.5 676.2 686.2 688.4 513.2 603.6 648.4 670.9 677.3 7900.1 9222.0 9320.7 9686.3 9850.0 521.7 530.9 532.0 672.5 678.2 4351.4 4392.5 4417.2 4469.1 4561.2 155053.0 157423.0 160859.2 162800.0 162892.0 130780.3 132471.0 135973.0 140036.3 142350.0 118991.4 119342.4 119939.4 121446.0 124407.8 8811.0 8895.1 8971.3 8997.4 9074.7 56229.9 64020.0 65741.0 66569.7 66984.0 92416.0 95158.5 98943.5 100260.5 101483.5 11322.5 11545.6 11634.8 11791.2 11978.9 12311.2 12745.4 12922.9 13091.7 13382.5 11130.7 12046.9 12067.6 12077.5 12109.4 11943.5 12134.2 12159.7 12231.8 12343.3 11801.9 12019.6 12123.0 12132.4 12341.1 10273.5 10318.9 10400.9 10443.2 10733.2 8946.0 10485.8 10557.2 10579.2 10647.4 12:48:35 INFO - PROCESS | 1661 | -------- Summary: end -------- 12:48:35 INFO - PROCESS | 1661 | 12:48:36 INFO - TEST-INFO | 1661: exit 0 12:48:36 INFO - TEST-OK | dromaeo_css | took 1115769ms 12:48:36 INFO - TEST-START | kraken 12:48:36 INFO - operating with platform_type : mac_ 12:48:36 INFO - Initialising browser for kraken test... 12:48:36 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpP1ON6m/profile http://localhost:49273/getInfo.html) 12:48:39 INFO - PROCESS | 1669 | 2016-10-06 12:48:39.051 plugin-container[1670:10039] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b2f, name = 'com.apple.tsm.portname' 12:48:39 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:39 INFO - PROCESS | 1669 | 2016-10-06 12:48:39.054 plugin-container[1670:10039] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 12:48:39 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:39 INFO - PROCESS | 1669 | 2016-10-06 12:48:39.054 plugin-container[1670:10039] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:48:39 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 12:48:39 INFO - PROCESS | 1669 | colorDepth:24 12:48:39 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 12:48:39 INFO - PROCESS | 1669 | __metrics 12:48:39 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 12:48:39 INFO - TEST-INFO | 1669: exit 0 12:48:39 INFO - Browser initialized. 12:48:39 INFO - Running cycle 1/1 for kraken test... 12:48:39 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpP1ON6m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:48:42 INFO - PROCESS | 1671 | 2016-10-06 12:48:42.284 plugin-container[1672:10156] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.tsm.portname' 12:48:42 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:42 INFO - PROCESS | 1671 | 2016-10-06 12:48:42.287 plugin-container[1672:10156] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f07, name = 'com.apple.CFPasteboardClient' 12:48:42 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:42 INFO - PROCESS | 1671 | 2016-10-06 12:48:42.287 plugin-container[1672:10156] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:48:51 INFO - PROCESS | 1671 | RSS: Main: 240386048 12:48:51 INFO - PROCESS | 1671 | 12:48:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 12:48:55 INFO - PROCESS | 1671 | RSS: Main: 254021632 12:48:55 INFO - PROCESS | 1671 | 12:48:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 12:48:58 INFO - PROCESS | 1671 | RSS: Main: 261521408 12:48:58 INFO - PROCESS | 1671 | 12:49:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 12:49:02 INFO - PROCESS | 1671 | RSS: Main: 262180864 12:49:02 INFO - PROCESS | 1671 | 12:49:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 12:49:05 INFO - PROCESS | 1671 | RSS: Main: 264577024 12:49:05 INFO - PROCESS | 1671 | 12:49:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:08 INFO - PROCESS | 1671 | RSS: Main: 264916992 12:49:08 INFO - PROCESS | 1671 | 12:49:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:11 INFO - PROCESS | 1671 | RSS: Main: 265891840 12:49:11 INFO - PROCESS | 1671 | 12:49:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:14 INFO - PROCESS | 1671 | RSS: Main: 265740288 12:49:14 INFO - PROCESS | 1671 | 12:49:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 12:49:18 INFO - PROCESS | 1671 | RSS: Main: 266289152 12:49:18 INFO - PROCESS | 1671 | 12:49:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:49:20 INFO - PROCESS | 1671 | RSS: Main: 265445376 12:49:20 INFO - PROCESS | 1671 | 12:49:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:49:23 INFO - PROCESS | 1671 | RSS: Main: 265515008 12:49:23 INFO - PROCESS | 1671 | 12:49:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:49:26 INFO - PROCESS | 1671 | RSS: Main: 265609216 12:49:26 INFO - PROCESS | 1671 | 12:49:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:49:30 INFO - PROCESS | 1671 | RSS: Main: 265797632 12:49:30 INFO - PROCESS | 1671 | 12:49:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:49:33 INFO - PROCESS | 1671 | RSS: Main: 265895936 12:49:33 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 12:49:35 INFO - PROCESS | 1671 | RSS: Main: 267423744 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | __start_tp_report 12:49:35 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 12:49:35 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 12:49:35 INFO - PROCESS | 1671 | |i|pagename|runs| 12:49:35 INFO - PROCESS | 1671 | |0;ai-astar;116;120;124;117;120;118;117;138;145;128 12:49:35 INFO - PROCESS | 1671 | |1;audio-beat-detection;156;117;115;152;115;119;117;118;144;114 12:49:35 INFO - PROCESS | 1671 | |2;audio-dft;194;200;205;233;218;195;193;205;201;196 12:49:35 INFO - PROCESS | 1671 | |3;audio-fft;82;62;51;95;82;85;52;71;103;53 12:49:35 INFO - PROCESS | 1671 | |4;audio-oscillator;89;73;110;106;101;103;96;70;71;87 12:49:35 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;89;87;88;85;89;85;92;86;90;86 12:49:35 INFO - PROCESS | 1671 | |6;imaging-darkroom;95;96;96;96;95;96;96;125;96;95 12:49:35 INFO - PROCESS | 1671 | |7;imaging-desaturate;145;82;153;139;122;96;96;95;98;96 12:49:35 INFO - PROCESS | 1671 | |8;json-parse-financial;56;57;56;57;58;57;57;57;57;56 12:49:35 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;71;70;78;77;74;69;70;70;71;70 12:49:35 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;92;98;86;88;88;103;118;111;84;85 12:49:35 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;143;140;178;160;162;148;143;147;184;174 12:49:35 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;176;176;174;174;176;196;237;177;176;189 12:49:35 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;70;73;79;69;80;72;62;76;82;91 12:49:35 INFO - PROCESS | 1671 | __end_tp_report 12:49:35 INFO - PROCESS | 1671 | __start_cc_report 12:49:35 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,942 12:49:35 INFO - PROCESS | 1671 | __end_cc_report 12:49:35 INFO - PROCESS | 1671 | __startTimestamp1475783375825__endTimestamp 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | ------- Summary: start ------- 12:49:35 INFO - PROCESS | 1671 | Number of tests: 14 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:124.30 Median:122.00 stddev:9.90 (8.1%) stddev-sans-first:10.03 12:49:35 INFO - PROCESS | 1671 | Values: 116.0 120.0 124.0 117.0 120.0 118.0 117.0 138.0 145.0 128.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:118.50 stddev:16.85 (14.2%) stddev-sans-first:14.15 12:49:35 INFO - PROCESS | 1671 | Values: 156.0 117.0 115.0 152.0 115.0 119.0 117.0 118.0 144.0 114.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:204.00 Median:203.00 stddev:12.61 (6.2%) stddev-sans-first:12.84 12:49:35 INFO - PROCESS | 1671 | Values: 194.0 200.0 205.0 233.0 218.0 195.0 193.0 205.0 201.0 196.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.60 Median:76.50 stddev:18.67 (24.4%) stddev-sans-first:19.55 12:49:35 INFO - PROCESS | 1671 | Values: 82.0 62.0 51.0 95.0 82.0 85.0 52.0 71.0 103.0 53.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:72.00 stddev:15.05 (20.9%) stddev-sans-first:15.95 12:49:35 INFO - PROCESS | 1671 | Values: 89.0 73.0 110.0 106.0 101.0 103.0 96.0 70.0 71.0 87.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:2.31 (2.6%) stddev-sans-first:2.40 12:49:35 INFO - PROCESS | 1671 | Values: 89.0 87.0 88.0 85.0 89.0 85.0 92.0 86.0 90.0 86.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:98.60 Median:96.00 stddev:9.29 (9.7%) stddev-sans-first:9.76 12:49:35 INFO - PROCESS | 1671 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 96.0 125.0 96.0 95.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:112.20 Median:95.50 stddev:25.28 (26.5%) stddev-sans-first:23.86 12:49:35 INFO - PROCESS | 1671 | Values: 145.0 82.0 153.0 139.0 122.0 96.0 96.0 95.0 98.0 96.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 12:49:35 INFO - PROCESS | 1671 | Values: 56.0 57.0 56.0 57.0 58.0 57.0 57.0 57.0 57.0 56.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.00 stddev:3.20 (4.5%) stddev-sans-first:3.37 12:49:35 INFO - PROCESS | 1671 | Values: 71.0 70.0 78.0 77.0 74.0 69.0 70.0 70.0 71.0 70.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:87.00 stddev:11.86 (13.6%) stddev-sans-first:12.52 12:49:35 INFO - PROCESS | 1671 | Values: 92.0 98.0 86.0 88.0 88.0 103.0 118.0 111.0 84.0 85.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:161.00 stddev:16.15 (10.0%) stddev-sans-first:16.20 12:49:35 INFO - PROCESS | 1671 | Values: 143.0 140.0 178.0 160.0 162.0 148.0 143.0 147.0 184.0 174.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.10 Median:176.50 stddev:19.64 (11.1%) stddev-sans-first:20.55 12:49:35 INFO - PROCESS | 1671 | Values: 176.0 176.0 174.0 174.0 176.0 196.0 237.0 177.0 176.0 189.0 12:49:35 INFO - PROCESS | 1671 | 12:49:35 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.40 Median:77.50 stddev:8.09 (10.4%) stddev-sans-first:8.34 12:49:35 INFO - PROCESS | 1671 | Values: 70.0 73.0 79.0 69.0 80.0 72.0 62.0 76.0 82.0 91.0 12:49:35 INFO - PROCESS | 1671 | -------- Summary: end -------- 12:49:35 INFO - PROCESS | 1671 | 12:49:36 INFO - TEST-INFO | 1671: exit 0 12:49:36 INFO - TEST-OK | kraken | took 60590ms 12:49:36 INFO - SUITE-END | took 1176s 12:49:36 INFO - Completed test suite (00:19:36) 12:49:36 INFO - javascript benchmark 12:49:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7185.402999237535, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.2012886713883, 3324.883629072983, 3388.390089783839, 3391.6608339166087, 3394.168428734959, 5224.4775522447735, 5350.2777125088105, 5609.439056094394, 5740.110282906146, 5772.278465191851, 5537.141743029827, 5644.689542075184, 5721.914171287423, 5782.335031471372, 5820.417958204188, 4281.67887408444, 5192.61055420845, 5256.526912577867, 5295.9672863791575, 5304.70824104676, 5219.45195754446, 5278.551323137519, 5365.382980957201, 5398.91901621476, 5413.052715774744, 5326.307580014612, 5338.679679219259, 5340.732963351817, 5345.118055520854, 5353.518183363496, 5132.538071573539, 5179.404368497633, 5231.058409486291, 5263.894722105575, 5315.574754019669, 5167.121589329785, 5201.167813149916, 5213.556847667953, 5227.451117632631, 5239.423663397023, 4563.06457176278, 5543.722813859331, 5777.815547812688, 5788.18965344852, 5809.157672137517, 4937.580305674022, 6033.48715359195, 6054.72753726079, 6057.3336932937345, 6124.724387402578, 6186.505079593621, 6188.783392581283, 6195.132681424604, 6198.0, 6202.255729312511, 5711.600187986801, 5718.25662663851, 5733.971330143406, 5736.971315143398, 5741.598088133832, 6125.571219981323, 6141.785037523665, 6168.938310616926, 6193.287771906198, 6222.253329600477, 6118.9846826261555, 6121.756825504462, 6126.426068710427, 6141.7543298267565, 6158.753649853956, 6126.0, 6152.138700581922, 6153.2000839890525, 6187.3503282154725, 6202.162708034448, 6085.786997455157, 6121.727351053096, 6132.78385080153, 6174.691265436711, 6175.752969881244, 6145.0, 6243.0635404688755, 6260.780872669526, 6260.874782504325, 6264.248290205204, 4943.629227807929, 4954.355933728592, 4958.727270000112, 4963.982383611351, 4973.751312434364, 4344.196323680066, 4407.184670835905, 4415.470143582791, 4446.911061778746, 4471.530489298641, 5752.597318187771, 5961.791337303173, 5981.641101533922, 5989.311229208697, 6008.969955150196, 6177.227846519185, 6227.906581401283, 6232.906506402408, 6249.187605611241, 6272.2159730033745, 4422.29243321067, 5171.65536960393, 5327.200919862052, 5336.706481143497, 5505.559555235572, 5366.436524164985, 5387.030334539742, 5438.483344082306, 5438.91841622376, 5473.808416705476, 5105.412877519133, 5606.803761868315, 5952.5833191676165, 6234.937650623436, 6464.385883341075, 4866.780994855169, 6217.937820621736, 6230.532710046855, 6233.065040244, 6236.501079913687, 5271.947280527145, 5739.7417116229035, 6210.975189093749, 6271.184745983175, 6350.047492876106, 5064.366954130734, 6196.535259855439, 6224.004159334485, 6228.626282423069, 6235.345288744798, 5009.624278179078, 6204.224471941007, 6211.565190436626, 6260.499160067093, 6292.772909282646, 6038.909416358846, 6092.360302168386, 6093.847653808691, 6104.0, 6144.0, 6102.825161501705, 6130.7241174146375, 6142.232220972379, 6148.877022459436, 6156.353582873913, 4854.878628034327, 5896.7936122235515, 5899.292084949662, 6003.909941350796, 6050.45545900871, 6085.178500902499, 6090.594617129203, 6104.298005729398, 6115.970584430425, 6155.84610384744, 5924.259467566554, 6033.5173186145885, 6045.546584006129, 6070.666113363631, 6081.604695694766], "value": 5623.946381175306, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.6612069913159, 508.3899320815021, 519.000923222796, 531.3544043986556, 558.8938101760667, 38228.23543529131, 40656.39015414764, 40806.775796726106, 40836.79581602088, 40934.79532602336, 41263.79368103163, 41741.582584174146, 41786.0, 41826.7908660457, 42038.36942445858, 9694.563744631509, 9755.95122024389, 9812.362196457207, 9872.753181170492, 9890.505474726271, 9782.90217097827, 9879.703608891708, 9908.256880733938, 9912.306138570302, 9921.950390248076, 9855.655052073178, 9869.654562090329, 9879.950600246988, 9896.208303335716, 9910.950445247763, 9690.612375504972, 9807.65673201438, 9837.213022958145, 9856.112949834549, 9879.357841740264, 9484.19384352331, 9529.857052144223, 9531.523423828781, 9612.759681007961, 9625.951870240604, 34492.655073449445, 35793.64206357929, 36436.635633643855, 39806.80096599527, 40042.0, 3239.2873567815045, 3312.893842707379, 3353.396388650042, 3396.252824378633, 3440.7591468597207, 30653.38693226123, 30860.0, 31236.37527249465, 31725.36549269025, 32035.839820800746, 3243.221626809572, 3261.706446419834, 3273.427150248721, 3356.026752241847, 3361.5629968104236, 477393.0, 481395.0, 481668.0, 482810.0, 485005.0, 60984.0, 61507.0, 61522.0, 61543.38456615466, 61618.383816161266, 41744.582554174674, 43277.35083973743, 43360.783196083816, 43910.34134487985, 43983.340249896275, 16284.185790710417, 16481.917590411973, 16482.505524834272, 16519.0, 16521.586960325847, 10566.841497377545, 12624.17942833713, 12739.108262421541, 12773.361331933365, 12815.16701414405, 17852.0459546278, 18645.160967756485, 18681.346152884584, 18730.0, 18891.0, 6471.288158302583, 6485.578437401648, 7014.018037474758, 7355.080614923135, 7915.604219789103, 811.5658122904218, 899.5786657081795, 993.4734590666958, 998.8651532043226, 1056.1920131099714, 22913.541729165656, 29607.0, 30012.0, 30306.0, 30347.937822176118, 1017.3692310767372, 1053.4258828938248, 1085.5006696919347, 1113.8273567597034, 1137.0789660375124, 15039.323230454656, 15496.14771187573, 15625.687486250212, 15659.608509787346, 15805.68388632221, 631715.0, 634814.0, 635226.0, 636553.0, 637206.4653857877, 56573.71713141408, 56837.0, 57044.144337834965, 57263.0, 57526.7123664379, 1334.7463981843418, 1360.9115407498482, 1386.3553447646893, 1387.0, 1389.610908945497, 28966.841326346712, 28976.275593109498, 29136.7086329134, 29384.559231611114, 29642.85178574093, 48024.55926322076, 48519.272210917574, 48671.75664121657, 48702.512974869795, 48857.5114248853, 593777.0, 600228.0, 601587.0, 602221.0, 603254.9023529302, 25968.480630386908, 26325.73674263233, 26397.0, 26659.466810664067, 26706.59940100939, 45898.77050614726, 46244.76877615591, 46876.7656161717, 46958.0, 47594.0, 38679.41980870233, 38700.612993870825, 38823.77030759134, 39842.40236396514, 39904.2019159621, 38154.61845381511, 38211.853644390714, 38632.227355452174, 39161.82514524569, 39498.80250598729, 31996.0, 32374.35251294914, 37052.62947370492, 37691.62308376881, 37698.81150594229, 27756.861215693792, 31821.522677160323, 32647.020589382355, 34454.0, 34535.48196777, 25125.623115652914, 25469.217154799728, 25544.103092165384, 25679.10123145681, 26174.0], "value": 19657.178961758967, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.8035460851141, 366.98348574314156, 373.3391896343471, 375.57559957248304, 393.7204584744831, 14053.718925621493, 18837.246510139594, 19957.301494447696, 20345.796542034594, 20619.484512887186, 19112.235510579594, 19222.51943701408, 19763.505912352197, 19786.70319945202, 19847.801521984777, 5610.186522954179, 5675.091985282355, 5910.822675319747, 5918.4969277611235, 5929.525637948954, 4898.804047838083, 5643.5767317451355, 5846.269216347957, 5876.265466816648, 5887.911681324784, 5548.223248745179, 5758.395368486312, 5788.6816225107605, 5798.768049278045, 5810.215620891189, 5649.067903795869, 5753.453421924932, 5775.768969241225, 5792.739326730307, 5806.042003069489, 4999.4250661174065, 5059.241113832954, 5060.924086138711, 5079.568236699885, 5362.249285100089, 12760.553380631725, 13044.08691391603, 13048.739025219538, 13056.281904495248, 19653.606927861507, 2103.82117520011, 2104.726385569866, 2145.7854214578574, 2194.5501172259646, 2458.5451691437142, 8185.263326300602, 8894.911050889537, 9427.90572094284, 9641.951790241073, 10180.185585153169, 1369.233229391544, 1712.2808420463434, 1800.4508624869409, 1862.9567442232258, 1868.8691791574595, 119964.0, 121537.0, 122106.0, 122446.0, 128896.35551822274, 9587.616495340248, 11066.336019838835, 11115.444227788577, 11206.43967801623, 11315.717107072225, 11071.612493562687, 14032.36854341557, 14148.504802331869, 14150.85849141516, 14190.716185676229, 8152.673893044212, 8304.086550479442, 8318.45930014547, 8442.409031367868, 8470.872936905951, 8107.473014254173, 8263.049749278789, 8404.033536143423, 8427.705030323908, 8441.15588441151, 4439.933400998988, 4443.600075993176, 4462.888427789267, 4574.016586433932, 4604.194266003436, 1915.9521011974812, 1942.3201879342116, 1959.0950514673225, 1962.313190383383, 1969.8522610804248, 798.8681867491799, 826.8222332198607, 864.7232885476587, 923.3490389275545, 954.5370495309769, 14764.818814494814, 19038.667293289338, 19760.70358944617, 20367.388978330673, 20899.79100209009, 828.0973738624928, 892.4288455388556, 892.834825557274, 992.3004281981185, 1002.4336250018716, 9617.509286060671, 10412.895871041193, 10541.525631346607, 10639.840402393971, 10740.677779666623, 528897.0, 541485.0, 548184.0, 553714.0, 598812.0, 127695.08457373147, 128432.0, 129807.0, 131546.0, 133581.0, 917.9082091790901, 923.6490133749132, 951.752544338477, 955.3694561589317, 958.4105774948357, 13765.518206862711, 17879.910600446914, 17959.73060404121, 17999.730004050212, 18046.81953180504, 45571.77214113908, 50106.49893501018, 53726.0, 53878.19182712184, 55250.44749552453, 54882.72558637341, 78696.0, 79615.20384796077, 79884.20115798767, 80638.0, 7690.548856259479, 7855.60721963911, 7883.684652614061, 8058.556779377191, 8112.918870811216, 10190.949045254727, 11721.648350549498, 12009.219400738924, 12064.51741930348, 12077.456514457046, 10184.338018028804, 10650.254482186172, 10708.143348531943, 10960.342379457257, 10980.945095274792, 9965.551550180115, 10239.897601023895, 10348.896511035095, 10583.4708264588, 10667.839982400115, 8757.868631970397, 9795.314327997257, 10909.127269818555, 10937.0, 11212.551497940318, 7829.530228186327, 7875.094364147967, 7981.920180798118, 8076.232757887991, 8131.340896596437, 7614.5811980339395, 8929.508877011827, 9968.800623987625, 10468.214883883587, 10678.89321106779], "value": 9017.599942896182, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1502.3389708528248, 1544.1815837606068, 1754.2895127473378, 1828.5154434074964, 1877.9436616901498, 7912.7626171214815, 8040.115587285394, 8269.421140520159, 8825.205731484166, 8847.29221662267, 8382.11987741286, 8386.790330241747, 8422.115677853813, 8570.657173713043, 8711.3902026858, 2247.550489902018, 2287.4281429642588, 2289.713785776778, 2290.7823756743082, 2297.6208925527267, 2102.4954011037316, 2119.565489074736, 2276.203328834911, 2302.458922153293, 2318.5826551220775, 2266.5240299537118, 2270.4664403865077, 2278.214016164429, 2280.680704701343, 2285.6914316567218, 2243.383069655841, 2255.9548809023727, 2264.0377839418184, 2275.306031660343, 2283.098176220392, 2238.1159442020476, 2251.133313674231, 2253.5943530164564, 2264.399934017487, 2271.5229801741652, 8718.389712720113, 8740.08229135938, 8828.24959878411, 8830.911690883073, 8863.025067242597, 5069.239614057884, 5223.817166399158, 5230.476952304777, 5233.21501774733, 5239.292695486099, 2617.6858776946697, 2882.8126171798976, 3071.769617278713, 3393.4400823864034, 3531.876384326536, 1026.650938680852, 1054.8470471781625, 1067.3702515515806, 1086.581666058573, 1090.9727256818564, 10986.395748233845, 11004.834927476095, 11107.000369966741, 11241.550337986471, 11278.323300601907, 4281.97859010703, 4368.934465982949, 4418.293073108352, 4477.462704475419, 4482.7758612069265, 8739.0, 8881.555922203865, 8888.422252553562, 8902.910970890338, 8911.0, 5030.874228144253, 5043.596512279082, 5047.369078865142, 5049.495050494995, 5055.9241611375855, 4317.460317460317, 4334.0248444099825, 4684.625229981593, 4749.311349854252, 4769.952300476951, 3612.313660404567, 3625.220577575834, 3680.7179548679455, 3704.111013356829, 3724.404095344732, 1063.6277302944063, 1110.21729680575, 1139.3904261220373, 1142.4402042999036, 1150.1431433581868, 772.6098320348189, 800.139849661616, 879.2789912272003, 906.6201221792794, 934.2245935873344, 4771.66598338113, 4819.831305904277, 4850.660453768202, 5593.049181639131, 5670.971645141748, 426.2498003513776, 426.88474111989586, 428.87133859841924, 433.14886248521805, 964.8793900762405, 3769.8878830744998, 3844.1927195288745, 3901.9609803902167, 4017.7991100445447, 4032.052467670095, 9817.803643927082, 10479.0, 10561.89438105609, 10599.0, 10607.416592087355, 10522.842157367646, 10545.420001899816, 10572.629957951434, 10578.788424231472, 10636.734081647866, 7900.2889739923685, 8041.678332866737, 8045.5574943377505, 8060.4760690556095, 8065.8790118149445, 5533.750981205775, 5675.404082571271, 5727.828165155052, 5771.134329850556, 5833.912491312549, 9702.757431063998, 9794.82462104552, 9800.460974646463, 9827.508624568885, 9841.311108222355, 9625.56684949165, 9914.702558923243, 10042.397456152654, 10056.798864022825, 10086.79826403453, 5941.584089113893, 6414.069959855888, 6583.144191255106, 6866.828329291808, 6952.165740111017, 7967.9203207967175, 8246.422750407413, 8254.504729716236, 8262.380321476034, 8262.628181731956, 7179.569225846466, 7308.5614863108385, 7356.595387253752, 7425.257474252531, 7427.962860185665, 7126.679299431435, 7149.642517874189, 7176.96411517939, 7195.640217989185, 7215.6031418270395, 6737.629430381376, 6846.931530684828, 6868.793936181923, 6870.003849441903, 6885.311468853075, 4919.557239848431, 4920.803167873388, 5004.449510553909, 5012.724300163526, 5038.571721403575, 4901.485344038824, 4904.0, 5006.6495345325475, 5061.8734531637, 5122.820701275437], "value": 4351.269442657365, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.18281874644833, 292.34514687100886, 306.0710742895312, 312.1151535397149, 314.37439495404124, 5092.847214583564, 5140.408852981908, 5226.0, 5379.488948549882, 5562.9443705562935, 4410.162069206848, 5196.778756992104, 5247.711375874325, 5305.206777154334, 5469.507744303012, 4055.6147166019327, 4172.0, 4176.6241038306625, 4183.142455796555, 4204.285271503852, 3669.4128939369707, 3883.3980732986433, 3884.6892248620043, 4198.496180458334, 4223.239816832969, 3504.4918486819397, 4107.77407242603, 4109.773962432065, 4138.193052354792, 4174.70777045607, 4069.308217603, 4121.5878412158845, 4134.594237959093, 4171.457710497646, 4192.853250136231, 3315.983420082908, 3580.6598373154507, 3601.891943241707, 3604.6575575320303, 3633.818309084562, 5118.795248190068, 5180.0, 5286.682799032032, 5408.675479471244, 5418.126039881504, 4100.462326627515, 4159.792010399498, 4535.863924082283, 4738.597219236369, 4741.193997020515, 4823.0, 5768.673205162794, 5779.537636989031, 5789.536837053026, 5852.619579727304, 3260.522003277526, 3782.224643947984, 3946.802659867024, 3957.6438120569, 3967.4725230786357, 5325.813596524103, 5985.700714964234, 6088.786892458743, 6216.073017364677, 6268.780592679234, 3987.940180897289, 4006.5392479864604, 4009.3384591542654, 4017.779022153751, 4020.597940205956, 3890.7665540067687, 3973.2252210819192, 4020.296448121567, 4061.593840615915, 4095.2219078375015, 4180.519240287345, 4207.789610519461, 4457.331400289982, 4498.775061246924, 4726.976365118221, 5889.823305300848, 5907.734151963172, 5913.70431478433, 5934.0, 5953.732082056318, 1282.7819270723999, 1311.6196303072193, 1336.432016393029, 1372.025861638248, 1405.8242719660043, 711.4130842055325, 759.7037155509356, 833.1377024779446, 836.4521238588734, 842.5408152556871, 4327.2643650579475, 4452.844150454784, 4562.581831629242, 4562.657800664963, 4635.559621835988, 4719.787609557578, 4863.902721945612, 4898.978546989293, 5010.749462526859, 5083.38999320084, 3697.44707222967, 3815.980920095382, 3976.701747368959, 3997.8206429103484, 4262.850800221977, 2587.8059145564157, 2678.6606696651675, 3009.495252373813, 3127.781055326105, 3179.3467396953456, 4718.504557021532, 5876.529877609866, 6278.843028924222, 6337.7781777637565, 6344.714487848058, 5261.789528418897, 5490.615656904059, 5546.389897111315, 6168.766246750578, 6308.274548426898, 5147.716875571805, 5565.805196818092, 6303.681977845191, 6315.084647518568, 6325.240971083499, 5069.340985671839, 6037.426444487766, 6044.214252147192, 6107.838876149646, 6138.846528836815, 5127.923081153711, 5676.262085928803, 5919.24128178601, 5971.552133589911, 5985.610935289193, 6221.720022598904, 6229.813105606839, 6268.592541484789, 6287.74849006053, 6385.584936979081, 3441.8451169696923, 3562.9845494034075, 3903.902402439962, 3925.509311336083, 3936.8386326033315, 4482.394876691605, 4852.733099679552, 5058.013687330935, 5834.38738932406, 5884.382139875252, 5323.387810401853, 5701.486866181897, 5719.256496655408, 5891.675957822361, 6023.3073196582955, 4809.158397280392, 5932.6737029462065, 5935.554833387427, 5954.940450595439, 5961.198236828684, 4521.728696278103, 5015.871428928462, 5448.0, 5689.947359738461, 5749.683767392834, 4936.703797772001, 5857.414258574108, 5917.467427931507, 5949.8215053548465, 5960.5827592068135, 4694.43666759991, 5829.59192856513, 5841.32824725162, 5850.6782126981725, 5857.891159182605], "value": 4109.983370022901, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.7248479457052, 677.5392732941599, 696.5542053086023, 710.8720430322539, 765.6516285090285, 15025.32386042628, 17108.74336884946, 17725.025123618212, 17729.0, 18240.72638910414, 16131.354745810182, 16695.83304166958, 16749.246283917222, 16801.411950581733, 16848.83151168488, 700.2157583506454, 852.1308265569115, 883.3198437203349, 885.3979294080023, 885.6280362247871, 703.1772825793834, 855.9315254779602, 870.8650159225331, 886.2865393358344, 888.3825741109899, 910.3491003932187, 920.7606022434148, 921.2123634292684, 921.8525035994209, 922.9769255768626, 867.3624885708999, 873.9038705742363, 894.5214310343968, 916.9633214671405, 922.7785331520421, 659.8977158540386, 688.900109484127, 690.0201713566747, 691.1602403080232, 691.87892118879, 15458.381664733397, 15467.0, 15467.0, 15472.226388680521, 15516.0, 12369.62891113268, 13295.268760218185, 13844.377003034888, 13901.235432051233, 14166.85833141676, 11448.599299024578, 11598.246114002563, 11651.883481165165, 11926.582569610022, 12182.512699492097, 3738.1962877981364, 3765.4540091686554, 3802.657760801541, 3914.9608503914883, 3924.2543916656027, 108821.45589272083, 111141.44429277883, 115070.42464787622, 116128.0, 118207.81792182055, 10400.0, 10534.20993425496, 10592.629257975934, 10681.359118452918, 10688.198385121146, 20289.391318260477, 21029.789702102782, 21144.0, 21276.468088297916, 21850.89074554617, 7876.803079922933, 8018.3184429324165, 8100.2304781045705, 8161.55111468863, 8224.136465671018, 3660.213054193361, 3881.029742564359, 4362.236608593484, 4388.824447022147, 4426.0, 2961.822290662524, 3516.4549494828343, 3628.6552777486095, 3638.0904773806546, 3698.519192505011, 567.3418834152363, 664.4850241063215, 676.1683129750336, 686.2382755141789, 688.421725750372, 513.2422443944613, 603.5684485592734, 648.3840351665842, 670.9499633074223, 677.3294438505844, 7900.130985591581, 9222.0, 9320.72037838866, 9686.273529485317, 9849.950750246202, 521.6817741177879, 530.8593222795962, 532.0263917031823, 672.5292295393244, 678.1556961582762, 4351.43431353922, 4392.472903251631, 4417.2269852775635, 4469.10617876426, 4561.201789686792, 155053.0, 157423.0, 160859.19570402073, 162800.0, 162892.0, 130780.3460982689, 132471.0, 135973.0, 140036.29981850027, 142350.0, 118991.40504297423, 119342.403287983, 119939.40030299792, 121446.0, 124407.75592244143, 8811.0, 8895.06601806801, 8971.32715046361, 8997.415168014059, 9074.682386116454, 56229.87540249254, 64020.0, 65741.0, 66569.66715166393, 66984.0, 92416.0, 95158.52420737852, 98943.50528247312, 100260.48046396404, 101483.49258253661, 11322.490487927898, 11545.59590414365, 11634.76730465403, 11791.233569817934, 11978.940105299418, 12311.19977201479, 12745.426455809325, 12922.870771292166, 13091.672708182372, 13382.531611393944, 11130.721731956766, 12046.939765301116, 12067.637970860887, 12077.456514456695, 12109.394530273627, 11943.462544185706, 12134.150609457252, 12159.696007599881, 12231.819726871256, 12343.321117338273, 11801.881981180422, 12019.579314723942, 12123.0, 12132.393380330772, 12341.136120471481, 10273.486325683836, 10318.870674679994, 10400.913182831051, 10443.164546836082, 10733.248672593156, 8946.0, 10485.842712359168, 10557.20820938448, 10579.15366770641, 10647.361158330525], "value": 7718.819031774381, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 120.0, 124.0, 117.0, 120.0, 118.0, 117.0, 138.0, 145.0, 128.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 117.0, 115.0, 152.0, 115.0, 119.0, 117.0, 118.0, 144.0, 114.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 200.0, 205.0, 233.0, 218.0, 195.0, 193.0, 205.0, 201.0, 196.0], "value": 204.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 51.0, 95.0, 82.0, 85.0, 52.0, 71.0, 103.0, 53.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 73.0, 110.0, 106.0, 101.0, 103.0, 96.0, 70.0, 71.0, 87.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 85.0, 89.0, 85.0, 92.0, 86.0, 90.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 125.0, 96.0, 95.0], "value": 98.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 82.0, 153.0, 139.0, 122.0, 96.0, 96.0, 95.0, 98.0, 96.0], "value": 112.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 78.0, 77.0, 74.0, 69.0, 70.0, 70.0, 71.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 86.0, 88.0, 88.0, 103.0, 118.0, 111.0, 84.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 178.0, 160.0, 162.0, 148.0, 143.0, 147.0, 184.0, 174.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 174.0, 174.0, 176.0, 196.0, 237.0, 177.0, 176.0, 189.0], "value": 185.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 79.0, 69.0, 80.0, 72.0, 62.0, 76.0, 82.0, 91.0], "value": 75.4, "unit": "score"}]}]} 12:49:37 INFO - Return code: 0 12:49:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:49:37 ERROR - # TBPL SUCCESS # 12:49:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:49:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:49:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:49:37 INFO - 2016-10-06 12:49:37.142 screenresolution[1679:10736] starting screenresolution argv=screenresolution get 12:49:37 INFO - 2016-10-06 12:49:37.145 screenresolution[1679:10736] Display 0: 1600x1200x32@60 12:49:37 INFO - 2016-10-06 12:49:37.151 screenresolution[1680:10738] starting screenresolution argv=screenresolution list 12:49:37 INFO - Available Modes on Display 0 12:49:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:49:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:49:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:49:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:49:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:49:37 INFO - Intel Iris: 12:49:37 INFO - Chipset Model: Intel Iris 12:49:37 INFO - Type: GPU 12:49:37 INFO - Bus: Built-In 12:49:37 INFO - VRAM (Dynamic, Max): 1536 MB 12:49:37 INFO - Vendor: Intel (0x8086) 12:49:37 INFO - Device ID: 0x0a2e 12:49:37 INFO - Revision ID: 0x0009 12:49:37 INFO - Displays: 12:49:37 INFO - Display: 12:49:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:49:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:49:37 INFO - Display Serial Number: 1600x1200 60 12:49:37 INFO - Main Display: Yes 12:49:37 INFO - Mirror: Off 12:49:37 INFO - Online: Yes 12:49:37 INFO - Rotation: Supported 12:49:37 INFO - Return code: 0 12:49:37 INFO - Running post-action listener: _resource_record_post_action 12:49:37 INFO - [mozharness: 2016-10-06 19:49:37.400225Z] Finished run-tests step (success) 12:49:37 INFO - Running post-run listener: _resource_record_post_run 12:49:37 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38054400; Write bytes: 711347200; Read time: 241; Write time: 2084 12:49:37 INFO - TinderboxPrint: CPU usage
54.6% 12:49:37 INFO - TinderboxPrint: I/O read bytes / time
38,054,400 / 241 12:49:37 INFO - TinderboxPrint: I/O write bytes / time
711,347,200 / 2,084 12:49:37 INFO - TinderboxPrint: CPU idle
2,199.5 (45.4%) 12:49:37 INFO - TinderboxPrint: CPU system
984.0 (20.3%) 12:49:37 INFO - TinderboxPrint: CPU user
1,662.2 (34.3%) 12:49:37 INFO - TinderboxPrint: Swap in / out
445,960,192 / 0 12:49:37 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400206336; Write bytes: 393984000; Read time: 29562; Write time: 835 12:49:37 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 36059648; Write bytes: 300471296; Read time: 224; Write time: 1202 12:49:37 INFO - Running post-run listener: _upload_blobber_files 12:49:37 INFO - Blob upload gear active. 12:49:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:49:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:49:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:49:38 INFO - (blobuploader) - INFO - Open directory for files ... 12:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:49:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:39 INFO - (blobuploader) - INFO - Done attempting. 12:49:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:49:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:40 INFO - (blobuploader) - INFO - Done attempting. 12:49:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:49:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:49:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:43 INFO - (blobuploader) - INFO - Done attempting. 12:49:43 INFO - (blobuploader) - INFO - Iteration through files over. 12:49:43 INFO - Return code: 0 12:49:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:49:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:49:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c75995cac96a7755453f2d86920382e77465df30ef3c333aed6174103b5570c0a3ede5d9b215b9d756daa76dbdad152a14d9c8656db03133c305034a6fb6269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/00f490ed035f8804bde5acf0f1f1bfa8c9101259e45ab1a83b96e819507aad8d5413ada4ab500242d46bb184f3ecc317805d8b0d3865902c0a4c6fbe1d38b843", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faec5d176006139bd8acab89ac2fe9134c59beb0dd6dc7ffded7c299ed9f68e90cdb84a31e46987d05b652c63427fa5761ad9e208b7714de475aee5447d800d1"} 12:49:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:49:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:49:43 INFO - Contents: 12:49:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c75995cac96a7755453f2d86920382e77465df30ef3c333aed6174103b5570c0a3ede5d9b215b9d756daa76dbdad152a14d9c8656db03133c305034a6fb6269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/00f490ed035f8804bde5acf0f1f1bfa8c9101259e45ab1a83b96e819507aad8d5413ada4ab500242d46bb184f3ecc317805d8b0d3865902c0a4c6fbe1d38b843", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faec5d176006139bd8acab89ac2fe9134c59beb0dd6dc7ffded7c299ed9f68e90cdb84a31e46987d05b652c63427fa5761ad9e208b7714de475aee5447d800d1"} 12:49:43 INFO - Running post-run listener: copy_logs_to_upload_dir 12:49:43 INFO - Copying logs to upload dir... 12:49:43 INFO - mkdir: /builds/slave/test/build/upload/logs 12:49:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.889896 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-06 12:49:43.225353) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 12:49:43.229217) ========= 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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c75995cac96a7755453f2d86920382e77465df30ef3c333aed6174103b5570c0a3ede5d9b215b9d756daa76dbdad152a14d9c8656db03133c305034a6fb6269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/00f490ed035f8804bde5acf0f1f1bfa8c9101259e45ab1a83b96e819507aad8d5413ada4ab500242d46bb184f3ecc317805d8b0d3865902c0a4c6fbe1d38b843", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faec5d176006139bd8acab89ac2fe9134c59beb0dd6dc7ffded7c299ed9f68e90cdb84a31e46987d05b652c63427fa5761ad9e208b7714de475aee5447d800d1"} build_url:https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011038 build_url: 'https://queue.taskcluster.net/v1/task/KR4vo4cURdm95-U8E0U2Sg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1c75995cac96a7755453f2d86920382e77465df30ef3c333aed6174103b5570c0a3ede5d9b215b9d756daa76dbdad152a14d9c8656db03133c305034a6fb6269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/00f490ed035f8804bde5acf0f1f1bfa8c9101259e45ab1a83b96e819507aad8d5413ada4ab500242d46bb184f3ecc317805d8b0d3865902c0a4c6fbe1d38b843", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faec5d176006139bd8acab89ac2fe9134c59beb0dd6dc7ffded7c299ed9f68e90cdb84a31e46987d05b652c63427fa5761ad9e208b7714de475aee5447d800d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 12:49:43.262314) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 12:49:43.262685) ========= 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.CWvZIdNPA5/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.nUYhpm8tw4/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.005245 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 12:49:43.312140) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 12:49:43.312519) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 12:49:43.312881) ========= ========= Total master_lag: 0.18 =========