builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1479141115.02 results: success (0) buildid: 20161114060943 builduid: 4c048b8ac3ec4a219ea40e049fcf6ad9 revision: 458c900dd4ef310d5bffae1f2bb97da50839cc66 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.019092) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.019579) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.019865) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.045583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.046006) ========= 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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-11-14 08:31:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.2M=0s 2016-11-14 08:31:55 (29.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092951 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.162199) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.162552) ========= 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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.025789 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:55.211069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 08:31:55.211479) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 458c900dd4ef310d5bffae1f2bb97da50839cc66 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 458c900dd4ef310d5bffae1f2bb97da50839cc66 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0sTco5RXMJ/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.0TkX7RW4wL/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-11-14 08:31:55,262 truncating revision to first 12 chars 2016-11-14 08:31:55,263 Setting DEBUG logging. 2016-11-14 08:31:55,263 attempt 1/10 2016-11-14 08:31:55,263 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/458c900dd4ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 08:31:56,573 unpacking tar archive at: mozilla-central-458c900dd4ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.562898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 08:31:56.796714) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:56.797228) ========= script_repo_revision: 458c900dd4ef310d5bffae1f2bb97da50839cc66 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:56.797818) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:56.798265) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 08:31:56.817303) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 08:31:56.817765) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0sTco5RXMJ/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.0TkX7RW4wL/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:31:56 INFO - MultiFileLogger online at 20161114 08:31:56 in /builds/slave/test 08:31:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:31:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:31:56 INFO - {'append_to_log': False, 08:31:56 INFO - 'base_work_dir': '/builds/slave/test', 08:31:56 INFO - 'blob_upload_branch': 'Firefox', 08:31:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:31:56 INFO - 'branch': 'Firefox', 08:31:56 INFO - 'buildbot_json_path': 'buildprops.json', 08:31:56 INFO - 'config_files': ('talos/mac_config.py',), 08:31:56 INFO - 'default_actions': ('clobber', 08:31:56 INFO - 'read-buildbot-config', 08:31:56 INFO - 'download-and-extract', 08:31:56 INFO - 'populate-webroot', 08:31:56 INFO - 'create-virtualenv', 08:31:56 INFO - 'install', 08:31:56 INFO - 'run-tests'), 08:31:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:31:56 INFO - 'download_minidump_stackwalk': True, 08:31:56 INFO - 'download_symbols': 'ondemand', 08:31:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:31:56 INFO - 'tooltool.py': '/tools/tooltool.py', 08:31:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:31:56 INFO - '/tools/misc-python/virtualenv.py')}, 08:31:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:31:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:31:56 INFO - 'installer_path': 'installer.exe', 08:31:56 INFO - 'log_level': 'info', 08:31:56 INFO - 'log_name': 'talos', 08:31:56 INFO - 'log_to_console': True, 08:31:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:31:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:31:56 INFO - 'opt_config_files': (), 08:31:56 INFO - 'pip_index': False, 08:31:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:31:57 INFO - 'cmd': ('bash', 08:31:57 INFO - '-c', 08:31:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:31:57 INFO - 'enabled': True, 08:31:57 INFO - 'halt_on_failure': False, 08:31:57 INFO - 'name': 'check_screen_resolution'},), 08:31:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:31:57 INFO - 'cmd': ('bash', 08:31:57 INFO - '-c', 08:31:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:31:57 INFO - 'enabled': True, 08:31:57 INFO - 'halt_on_failure': False, 08:31:57 INFO - 'name': 'check_screen_resolution'},), 08:31:57 INFO - 'run_cmd_checks_enabled': True, 08:31:57 INFO - 'sps_profile': False, 08:31:57 INFO - 'sps_profile_interval': 0, 08:31:57 INFO - 'suite': 'dromaeojs-e10s', 08:31:57 INFO - 'system_bits': '32', 08:31:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:31:57 INFO - 'title': 't-yosemite-r7-0098', 08:31:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:31:57 INFO - 'use_talos_json': True, 08:31:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:31:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:31:57 INFO - 'work_dir': 'build'} 08:31:57 INFO - [mozharness: 2016-11-14 16:31:57.002731Z] Running clobber step. 08:31:57 INFO - Running pre-action listener: _resource_record_pre_action 08:31:57 INFO - Running main action method: clobber 08:31:57 INFO - rmtree: /builds/slave/test/build 08:31:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:31:59 INFO - Running post-action listener: _resource_record_post_action 08:31:59 INFO - [mozharness: 2016-11-14 16:31:59.449701Z] Finished clobber step (success) 08:31:59 INFO - [mozharness: 2016-11-14 16:31:59.449861Z] Running read-buildbot-config step. 08:31:59 INFO - Running pre-action listener: _resource_record_pre_action 08:31:59 INFO - Running main action method: read_buildbot_config 08:31:59 INFO - Using buildbot properties: 08:31:59 INFO - { 08:31:59 INFO - "project": "", 08:31:59 INFO - "product": "firefox", 08:31:59 INFO - "script_repo_revision": "production", 08:31:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:31:59 INFO - "repository": "", 08:31:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:31:59 INFO - "buildid": "20161114060943", 08:31:59 INFO - "pgo_build": "False", 08:31:59 INFO - "basedir": "/builds/slave/test", 08:31:59 INFO - "buildnumber": 10, 08:31:59 INFO - "slavename": "t-yosemite-r7-0098", 08:31:59 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:31:59 INFO - "platform": "yosemite_r7", 08:31:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:31:59 INFO - "branch": "mozilla-central", 08:31:59 INFO - "repo_path": "mozilla-central", 08:31:59 INFO - "revision": "458c900dd4ef310d5bffae1f2bb97da50839cc66", 08:31:59 INFO - "stage_platform": "macosx64", 08:31:59 INFO - "builduid": "4c048b8ac3ec4a219ea40e049fcf6ad9", 08:31:59 INFO - "slavebuilddir": "test" 08:31:59 INFO - } 08:31:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:31:59 INFO - Running post-action listener: _resource_record_post_action 08:31:59 INFO - [mozharness: 2016-11-14 16:31:59.453269Z] Finished read-buildbot-config step (success) 08:31:59 INFO - [mozharness: 2016-11-14 16:31:59.453415Z] Running download-and-extract step. 08:31:59 INFO - Running pre-action listener: _resource_record_pre_action 08:31:59 INFO - Running main action method: download_and_extract 08:31:59 INFO - mkdir: /builds/slave/test/build/tests 08:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:59 INFO - trying https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:31:59 INFO - Downloading https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:32:00 INFO - Downloaded 1199 bytes. 08:32:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:32:00 INFO - Using the following test package requirements: 08:32:00 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:32:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:32:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:32:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'jsshell-mac.zip'], 08:32:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:32:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:32:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:32:00 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:32:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:32:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:32:00 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:32:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:32:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:32:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:32:00 INFO - Fetch https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:32:01 INFO - Http code: 200 08:32:01 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 08:32:01 INFO - x-amz-cf-id: Gl8coAV5ZsTzpmpDxp8_of-LYds0jgFmCsf93B8DPMLvh1Wz4kqwWg== 08:32:01 INFO - x-amz-version-id: VjrQbwq8UYeUFVY0H2A5i1MTfVQy7bNp 08:32:01 INFO - Expected file size: 16328806 08:32:01 INFO - Obtained file size: 16328806 08:32:08 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:32:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:32:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:32:08 INFO - Fetch https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:32:08 INFO - Http code: 200 08:32:08 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:32:08 INFO - x-amz-cf-id: qvVh59s1f4_Pv4rgU_bXMI5Dzltz4cvDbhA3kISry2v-CgJLfGwNaQ== 08:32:08 INFO - x-amz-version-id: VjrQbwq8UYeUFVY0H2A5i1MTfVQy7bNp 08:32:08 INFO - Expected file size: 16328806 08:32:08 INFO - Obtained file size: 16328806 08:32:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:32:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:32:15 INFO - Fetch https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:32:15 INFO - Http code: 200 08:32:15 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 08:32:15 INFO - x-amz-cf-id: uuT2N9c-if1EDg_TRWWfWeealqYYiWaFWB9Qz-Hj4PknjAVlumO9ig== 08:32:15 INFO - x-amz-version-id: VZ9INOa..20tQcO.sXWj5zbI9AJhUATO 08:32:16 INFO - Expected file size: 14409038 08:32:16 INFO - Obtained file size: 14409038 08:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:16 INFO - trying https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:32:16 INFO - Downloading https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:32:19 INFO - Downloaded 110966193 bytes. 08:32:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:32:19 INFO - mkdir: /builds/slave/test/properties 08:32:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:32:19 INFO - Writing to file /builds/slave/test/properties/build_url 08:32:19 INFO - Contents: 08:32:19 INFO - build_url:https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:32:20 INFO - Running post-action listener: _resource_record_post_action 08:32:20 INFO - Running post-action listener: set_extra_try_arguments 08:32:20 INFO - [mozharness: 2016-11-14 16:32:20.107551Z] Finished download-and-extract step (success) 08:32:20 INFO - [mozharness: 2016-11-14 16:32:20.107703Z] Running populate-webroot step. 08:32:20 INFO - Running pre-action listener: _resource_record_pre_action 08:32:20 INFO - Running main action method: populate_webroot 08:32:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:32:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:32:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:32:20 INFO - '--tppagecycles', 08:32:20 INFO - '7'], 08:32:20 INFO - 'tests': ['tsvgm']}}, 08:32:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:32:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:32:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:32:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:32:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['damp', 'tps']}, 08:32:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'tests': ['damp', 'tps']}, 08:32:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['dromaeo_dom']}, 08:32:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:32:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['basic_compositor_video']}, 08:32:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:32:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['a11yr', 08:32:20 INFO - 'ts_paint', 08:32:20 INFO - 'tpaint', 08:32:20 INFO - 'sessionrestore', 08:32:20 INFO - 'sessionrestore_no_auto_restore', 08:32:20 INFO - 'tabpaint']}, 08:32:20 INFO - 'other-e10s': {'tests': ['a11yr', 08:32:20 INFO - 'ts_paint', 08:32:20 INFO - 'tpaint', 08:32:20 INFO - 'sessionrestore', 08:32:20 INFO - 'sessionrestore_no_auto_restore', 08:32:20 INFO - 'tabpaint']}, 08:32:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:32:20 INFO - 'ts_paint', 08:32:20 INFO - 'tpaint', 08:32:20 INFO - 'sessionrestore', 08:32:20 INFO - 'sessionrestore_no_auto_restore', 08:32:20 INFO - 'tabpaint']}, 08:32:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:32:20 INFO - 'ts_paint', 08:32:20 INFO - 'tpaint', 08:32:20 INFO - 'sessionrestore', 08:32:20 INFO - 'sessionrestore_no_auto_restore', 08:32:20 INFO - 'tabpaint']}, 08:32:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['a11yr', 08:32:20 INFO - 'ts_paint', 08:32:20 INFO - 'tpaint', 08:32:20 INFO - 'sessionrestore', 08:32:20 INFO - 'sessionrestore_no_auto_restore', 08:32:20 INFO - 'tabpaint']}, 08:32:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['a11yr', 08:32:20 INFO - 'ts_paint', 08:32:20 INFO - 'tpaint', 08:32:20 INFO - 'sessionrestore', 08:32:20 INFO - 'sessionrestore_no_auto_restore', 08:32:20 INFO - 'tabpaint']}, 08:32:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['tsvgx', 08:32:20 INFO - 'tsvgr_opacity', 08:32:20 INFO - 'tart', 08:32:20 INFO - 'tscrollx', 08:32:20 INFO - 'cart']}, 08:32:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:32:20 INFO - 'tsvgr_opacity', 08:32:20 INFO - 'tart', 08:32:20 INFO - 'tscrollx', 08:32:20 INFO - 'cart']}, 08:32:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'talos_options': ['--disable-e10s'], 08:32:20 INFO - 'tests': ['tp5o']}, 08:32:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'tests': ['tp5o']}, 08:32:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'talos_options': ['--disable-e10s', 08:32:20 INFO - '--xperf_path', 08:32:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:20 INFO - 'tests': ['tp5n']}, 08:32:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:20 INFO - 'talos_options': ['--xperf_path', 08:32:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:20 INFO - 'tests': ['tp5n']}}, 08:32:20 INFO - 'talos.zip': {'path': '', 08:32:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:32:20 INFO - Running post-action listener: _resource_record_post_action 08:32:20 INFO - [mozharness: 2016-11-14 16:32:20.126310Z] Finished populate-webroot step (success) 08:32:20 INFO - [mozharness: 2016-11-14 16:32:20.126438Z] Running create-virtualenv step. 08:32:20 INFO - Running pre-action listener: _resource_record_pre_action 08:32:20 INFO - Running main action method: create_virtualenv 08:32:20 INFO - Creating virtualenv /builds/slave/test/build/venv 08:32:20 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 08:32:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:32:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:32:20 INFO - Using real prefix '/tools/python27' 08:32:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:32:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:32:23 INFO - Installing pip.................done. 08:32:23 INFO - Return code: 0 08:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:32:24 INFO - Reading from file tmpfile_stdout 08:32:24 INFO - Output received: 08:32:24 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) 08:32:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:24 INFO - 'HOME': '/Users/cltbld', 08:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:24 INFO - 'LOGNAME': 'cltbld', 08:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:24 INFO - 'MOZ_NO_REMOTE': '1', 08:32:24 INFO - 'NO_EM_RESTART': '1', 08:32:24 INFO - 'PAGER': '/bin/cat', 08:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:24 INFO - 'PWD': '/builds/slave/test', 08:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:24 INFO - 'SHELL': '/bin/bash', 08:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:24 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:24 INFO - 'USER': 'cltbld', 08:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:24 INFO - 'XPC_FLAGS': '0x0', 08:32:24 INFO - 'XPC_SERVICE_NAME': '0', 08:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:24 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 08:32:24 INFO - Cleaning up... 08:32:24 INFO - Return code: 0 08:32:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:24 INFO - 'HOME': '/Users/cltbld', 08:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:24 INFO - 'LOGNAME': 'cltbld', 08:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:24 INFO - 'MOZ_NO_REMOTE': '1', 08:32:24 INFO - 'NO_EM_RESTART': '1', 08:32:24 INFO - 'PAGER': '/bin/cat', 08:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:24 INFO - 'PWD': '/builds/slave/test', 08:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:24 INFO - 'SHELL': '/bin/bash', 08:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:24 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:24 INFO - 'USER': 'cltbld', 08:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:24 INFO - 'XPC_FLAGS': '0x0', 08:32:24 INFO - 'XPC_SERVICE_NAME': '0', 08:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:24 INFO - Downloading/unpacking psutil>=3.1.1 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:32:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:28 INFO - Installing collected packages: psutil 08:32:28 INFO - Running setup.py install for psutil 08:32:28 INFO - building 'psutil._psutil_osx' extension 08:32:28 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 08:32:28 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 08:32:28 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 08:32:28 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 08:32:28 INFO - building 'psutil._psutil_posix' extension 08:32:28 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 08:32:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:32:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:32:29 INFO - ^ 08:32:29 INFO - 1 warning generated. 08:32:29 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 08:32:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:29 INFO - Successfully installed psutil 08:32:29 INFO - Cleaning up... 08:32:29 INFO - Return code: 0 08:32:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:32:29 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')]} 08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:29 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')]} 08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:29 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:29 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 08:32:29 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 08:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:29 INFO - 'HOME': '/Users/cltbld', 08:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:29 INFO - 'LOGNAME': 'cltbld', 08:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:29 INFO - 'MOZ_NO_REMOTE': '1', 08:32:29 INFO - 'NO_EM_RESTART': '1', 08:32:29 INFO - 'PAGER': '/bin/cat', 08:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:29 INFO - 'PWD': '/builds/slave/test', 08:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:29 INFO - 'SHELL': '/bin/bash', 08:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:29 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:29 INFO - 'USER': 'cltbld', 08:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:29 INFO - 'XPC_FLAGS': '0x0', 08:32:29 INFO - 'XPC_SERVICE_NAME': '0', 08:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:32:29 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 08:32:29 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 08:32:29 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 08:32:29 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 08:32:29 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 08:32:29 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 08:32:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:32:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:32:32 INFO - Installing collected packages: mozsystemmonitor 08:32:32 INFO - Running setup.py install for mozsystemmonitor 08:32:32 INFO - Successfully installed mozsystemmonitor 08:32:32 INFO - Cleaning up... 08:32:32 INFO - Return code: 0 08:32:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:32:32 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')]} 08:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:32 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')]} 08:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:32 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:32 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 08:32:32 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 08:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:32 INFO - 'HOME': '/Users/cltbld', 08:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:32 INFO - 'LOGNAME': 'cltbld', 08:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:32 INFO - 'MOZ_NO_REMOTE': '1', 08:32:32 INFO - 'NO_EM_RESTART': '1', 08:32:32 INFO - 'PAGER': '/bin/cat', 08:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:32 INFO - 'PWD': '/builds/slave/test', 08:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:32 INFO - 'SHELL': '/bin/bash', 08:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:32 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:32 INFO - 'USER': 'cltbld', 08:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:32 INFO - 'XPC_FLAGS': '0x0', 08:32:32 INFO - 'XPC_SERVICE_NAME': '0', 08:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:32:32 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 08:32:32 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 08:32:32 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 08:32:32 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 08:32:32 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 08:32:32 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 08:32:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:32:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:32:35 INFO - warning: no files found matching '*.txt' 08:32:35 INFO - no previously-included directories found matching 'build' 08:32:35 INFO - no previously-included directories found matching 'dist' 08:32:35 INFO - no previously-included directories found matching '.git*' 08:32:35 INFO - Installing collected packages: jsonschema, functools32 08:32:35 INFO - Running setup.py install for functools32 08:32:35 INFO - warning: no files found matching '*.txt' 08:32:35 INFO - no previously-included directories found matching 'build' 08:32:35 INFO - no previously-included directories found matching 'dist' 08:32:35 INFO - no previously-included directories found matching '.git*' 08:32:35 INFO - Successfully installed jsonschema functools32 08:32:35 INFO - Cleaning up... 08:32:35 INFO - Return code: 0 08:32:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:32:35 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')]} 08:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:35 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')]} 08:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:35 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:35 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 08:32:35 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 08:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:35 INFO - 'HOME': '/Users/cltbld', 08:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:35 INFO - 'LOGNAME': 'cltbld', 08:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:35 INFO - 'MOZ_NO_REMOTE': '1', 08:32:35 INFO - 'NO_EM_RESTART': '1', 08:32:35 INFO - 'PAGER': '/bin/cat', 08:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:35 INFO - 'PWD': '/builds/slave/test', 08:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:35 INFO - 'SHELL': '/bin/bash', 08:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:35 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:35 INFO - 'USER': 'cltbld', 08:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:35 INFO - 'XPC_FLAGS': '0x0', 08:32:35 INFO - 'XPC_SERVICE_NAME': '0', 08:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:32:36 INFO - Cleaning up... 08:32:36 INFO - Return code: 0 08:32:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:36 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:36 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 08:32:36 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 08:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:36 INFO - 'HOME': '/Users/cltbld', 08:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:36 INFO - 'LOGNAME': 'cltbld', 08:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:36 INFO - 'MOZ_NO_REMOTE': '1', 08:32:36 INFO - 'NO_EM_RESTART': '1', 08:32:36 INFO - 'PAGER': '/bin/cat', 08:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:36 INFO - 'PWD': '/builds/slave/test', 08:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:36 INFO - 'SHELL': '/bin/bash', 08:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:36 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:36 INFO - 'USER': 'cltbld', 08:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:36 INFO - 'XPC_FLAGS': '0x0', 08:32:36 INFO - 'XPC_SERVICE_NAME': '0', 08:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:32:36 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 08:32:36 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 08:32:36 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 08:32:36 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 08:32:36 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 08:32:36 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 08:32:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:32:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:32:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 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 08:32:39 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 08:32:40 INFO - Downloading docopt-0.6.1.tar.gz 08:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:32:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:40 INFO - Running setup.py install for blobuploader 08:32:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:32:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:32:40 INFO - Running setup.py install for requests 08:32:40 INFO - Running setup.py install for docopt 08:32:41 INFO - Successfully installed blobuploader requests docopt 08:32:41 INFO - Cleaning up... 08:32:41 INFO - Return code: 0 08:32:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:41 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')]} 08:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:41 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')]} 08:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:41 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:41 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 08:32:41 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 08:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:41 INFO - 'HOME': '/Users/cltbld', 08:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:41 INFO - 'LOGNAME': 'cltbld', 08:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:41 INFO - 'MOZ_NO_REMOTE': '1', 08:32:41 INFO - 'NO_EM_RESTART': '1', 08:32:41 INFO - 'PAGER': '/bin/cat', 08:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:41 INFO - 'PWD': '/builds/slave/test', 08:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:41 INFO - 'SHELL': '/bin/bash', 08:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:41 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:41 INFO - 'USER': 'cltbld', 08:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:41 INFO - 'XPC_FLAGS': '0x0', 08:32:41 INFO - 'XPC_SERVICE_NAME': '0', 08:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-1CA5uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Cflzbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-jY0sal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-K6HMWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-mo0Lqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-CDbyQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-XhHcLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-2mUqna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-J8LtJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-EVkMQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-WFYMxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-j3_gMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-obeb7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-H4itUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ryX46k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-JlExNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:43 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-E46aYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:43 INFO - Running setup.py install for manifestparser 08:32:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:32:43 INFO - Running setup.py install for mozcrash 08:32:43 INFO - Running setup.py install for mozdebug 08:32:43 INFO - Running setup.py install for mozdevice 08:32:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:32:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:32:43 INFO - Running setup.py install for mozfile 08:32:43 INFO - Running setup.py install for mozhttpd 08:32:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Running setup.py install for mozinfo 08:32:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Running setup.py install for mozInstall 08:32:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Running setup.py install for mozleak 08:32:44 INFO - Running setup.py install for mozlog 08:32:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Running setup.py install for moznetwork 08:32:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:32:44 INFO - Running setup.py install for mozprocess 08:32:45 INFO - Running setup.py install for mozprofile 08:32:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:32:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:32:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:32:45 INFO - Running setup.py install for mozrunner 08:32:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:32:45 INFO - Running setup.py install for mozscreenshot 08:32:45 INFO - Running setup.py install for moztest 08:32:45 INFO - Running setup.py install for mozversion 08:32:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:32:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:45 INFO - Cleaning up... 08:32:45 INFO - Return code: 0 08:32:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:45 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')]} 08:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:45 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')]} 08:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:45 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:45 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 08:32:45 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 08:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:45 INFO - 'HOME': '/Users/cltbld', 08:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:45 INFO - 'LOGNAME': 'cltbld', 08:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:45 INFO - 'MOZ_NO_REMOTE': '1', 08:32:45 INFO - 'NO_EM_RESTART': '1', 08:32:45 INFO - 'PAGER': '/bin/cat', 08:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:45 INFO - 'PWD': '/builds/slave/test', 08:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:45 INFO - 'SHELL': '/bin/bash', 08:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:45 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:45 INFO - 'USER': 'cltbld', 08:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:45 INFO - 'XPC_FLAGS': '0x0', 08:32:45 INFO - 'XPC_SERVICE_NAME': '0', 08:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-BaDabG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-2dxytw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-jdmtuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-CY0lgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-m3Ah8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-AMPmU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-fMS7yS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-k8olGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:46 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)) 08:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-DzZ6Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-CXHOVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-OeSVhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-nr6cxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-R0QcYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-LTXGoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-C1BwWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-LwaIYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:47 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)) 08:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-T4dVf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:47 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)) 08:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:32:47 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)) 08:32:47 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)) 08:32:47 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)) 08:32:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32:47 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 08:32:47 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 08:32:47 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 08:32:47 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 08:32:47 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 08:32:47 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 08:32:50 INFO - Downloading blessings-1.6.tar.gz 08:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:32:50 INFO - Installing collected packages: blessings 08:32:50 INFO - Running setup.py install for blessings 08:32:50 INFO - Successfully installed blessings 08:32:50 INFO - Cleaning up... 08:32:50 INFO - Return code: 0 08:32:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:51 INFO - Reading from file tmpfile_stdout 08:32:51 INFO - Current package versions: 08:32:51 INFO - blessings == 1.6 08:32:51 INFO - blobuploader == 1.2.4 08:32:51 INFO - docopt == 0.6.1 08:32:51 INFO - functools32 == 3.2.3-2 08:32:51 INFO - jsonschema == 2.5.1 08:32:51 INFO - manifestparser == 1.1 08:32:51 INFO - mozInstall == 1.12 08:32:51 INFO - mozcrash == 1.0 08:32:51 INFO - mozdebug == 0.1 08:32:51 INFO - mozdevice == 0.48 08:32:51 INFO - mozfile == 1.2 08:32:51 INFO - mozhttpd == 0.7 08:32:51 INFO - mozinfo == 0.9 08:32:51 INFO - mozleak == 0.1 08:32:51 INFO - mozlog == 3.4 08:32:51 INFO - moznetwork == 0.27 08:32:51 INFO - mozprocess == 0.23 08:32:51 INFO - mozprofile == 0.28 08:32:51 INFO - mozrunner == 6.13 08:32:51 INFO - mozscreenshot == 0.1 08:32:51 INFO - mozsystemmonitor == 0.3 08:32:51 INFO - moztest == 0.7 08:32:51 INFO - mozversion == 1.4 08:32:51 INFO - psutil == 3.1.1 08:32:51 INFO - requests == 1.2.3 08:32:51 INFO - wsgiref == 0.1.2 08:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:51 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:51 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 08:32:51 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 08:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:51 INFO - 'HOME': '/Users/cltbld', 08:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:51 INFO - 'LOGNAME': 'cltbld', 08:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:51 INFO - 'MOZ_NO_REMOTE': '1', 08:32:51 INFO - 'NO_EM_RESTART': '1', 08:32:51 INFO - 'PAGER': '/bin/cat', 08:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:51 INFO - 'PWD': '/builds/slave/test', 08:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:51 INFO - 'SHELL': '/bin/bash', 08:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:51 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:51 INFO - 'USER': 'cltbld', 08:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:51 INFO - 'XPC_FLAGS': '0x0', 08:32:51 INFO - 'XPC_SERVICE_NAME': '0', 08:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 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)) 08:32:51 INFO - Cleaning up... 08:32:51 INFO - Return code: 0 08:32:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:52 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 0x1058ac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105822570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc050577430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, '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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 08:32:52 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 08:32:52 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 08:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:52 INFO - 'HOME': '/Users/cltbld', 08:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:52 INFO - 'LOGNAME': 'cltbld', 08:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:52 INFO - 'MOZ_NO_REMOTE': '1', 08:32:52 INFO - 'NO_EM_RESTART': '1', 08:32:52 INFO - 'PAGER': '/bin/cat', 08:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:52 INFO - 'PWD': '/builds/slave/test', 08:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:52 INFO - 'SHELL': '/bin/bash', 08:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:32:52 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:52 INFO - 'USER': 'cltbld', 08:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:52 INFO - 'XPC_FLAGS': '0x0', 08:32:52 INFO - 'XPC_SERVICE_NAME': '0', 08:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:32:52 INFO - Cleaning up... 08:32:52 INFO - Return code: 0 08:32:52 INFO - Running post-action listener: _resource_record_post_action 08:32:52 INFO - Running post-action listener: _start_resource_monitoring 08:32:52 INFO - Starting resource monitoring. 08:32:52 INFO - [mozharness: 2016-11-14 16:32:52.377677Z] Finished create-virtualenv step (success) 08:32:52 INFO - [mozharness: 2016-11-14 16:32:52.378634Z] Running install step. 08:32:52 INFO - Running pre-action listener: _resource_record_pre_action 08:32:52 INFO - Running main action method: install 08:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:52 INFO - Reading from file tmpfile_stdout 08:32:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:32:52 INFO - Reading from file tmpfile_stdout 08:32:52 INFO - Output received: 08:32:52 INFO - Usage: mozinstall [options] installer 08:32:52 INFO - Options: 08:32:52 INFO - -h, --help show this help message and exit 08:32:52 INFO - -d DEST, --destination=DEST 08:32:52 INFO - Directory to install application into. [default: 08:32:52 INFO - "/builds/slave/test"] 08:32:52 INFO - --app=APP Application being installed. [default: firefox] 08:32:52 INFO - mkdir: /builds/slave/test/build/application 08:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:33:26 INFO - Reading from file tmpfile_stdout 08:33:26 INFO - Output received: 08:33:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:33:26 INFO - Running post-action listener: _resource_record_post_action 08:33:26 INFO - [mozharness: 2016-11-14 16:33:26.567589Z] Finished install step (success) 08:33:26 INFO - [mozharness: 2016-11-14 16:33:26.567740Z] Running run-tests step. 08:33:26 INFO - Running pre-action listener: _resource_record_pre_action 08:33:26 INFO - Running main action method: run_tests 08:33:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:33:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:33:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:33:26 INFO - 2016-11-14 08:33:26.580 screenresolution[1640:7648] starting screenresolution argv=screenresolution get 08:33:26 INFO - 2016-11-14 08:33:26.582 screenresolution[1640:7648] Display 0: 1600x1200x32@60 08:33:26 INFO - 2016-11-14 08:33:26.587 screenresolution[1641:7650] starting screenresolution argv=screenresolution list 08:33:26 INFO - Available Modes on Display 0 08:33:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:33:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:33:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:33:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:33:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:33:26 INFO - Intel Iris: 08:33:26 INFO - Chipset Model: Intel Iris 08:33:26 INFO - Type: GPU 08:33:26 INFO - Bus: Built-In 08:33:26 INFO - VRAM (Dynamic, Max): 1536 MB 08:33:26 INFO - Vendor: Intel (0x8086) 08:33:26 INFO - Device ID: 0x0a2e 08:33:26 INFO - Revision ID: 0x0009 08:33:26 INFO - Displays: 08:33:26 INFO - Display: 08:33:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:33:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:33:26 INFO - Display Serial Number: 1600x1200 60 08:33:26 INFO - Main Display: Yes 08:33:26 INFO - Mirror: Off 08:33:26 INFO - Online: Yes 08:33:26 INFO - Rotation: Supported 08:33:26 INFO - Return code: 0 08:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:33:26 INFO - Python 2.7.3 08:33:26 INFO - Return code: 0 08:33:26 INFO - grabbing minidump binary from tooltool 08:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:26 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 0x1056c0d30>, '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 0x1054e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:33:26 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 08:33:26 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 08:33:26 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 08:33:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:33:26 INFO - Return code: 0 08:33:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:33:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:33:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:33:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:33:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 08:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --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 08:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0sTco5RXMJ/Render', 08:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:26 INFO - 'HOME': '/Users/cltbld', 08:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:26 INFO - 'LOGNAME': 'cltbld', 08:33:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:33:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:26 INFO - 'MOZ_NO_REMOTE': '1', 08:33:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:33:26 INFO - 'NO_EM_RESTART': '1', 08:33:26 INFO - 'PAGER': '/bin/cat', 08:33:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:26 INFO - 'PWD': '/builds/slave/test', 08:33:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:26 INFO - 'SHELL': '/bin/bash', 08:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TkX7RW4wL/Listeners', 08:33:26 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 08:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:26 INFO - 'USER': 'cltbld', 08:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:26 INFO - 'XPC_FLAGS': '0x0', 08:33:26 INFO - 'XPC_SERVICE_NAME': '0', 08:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 08:33:27 INFO - mozversion application_buildid: 20161114060943 08:33:27 INFO - mozversion application_changeset: 458c900dd4ef310d5bffae1f2bb97da50839cc66 08:33:27 INFO - mozversion application_display_name: Nightly 08:33:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:33:27 INFO - mozversion application_name: Firefox 08:33:27 INFO - mozversion application_remotingname: firefox 08:33:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:33:27 INFO - mozversion application_vendor: Mozilla 08:33:27 INFO - mozversion application_version: 53.0a1 08:33:27 INFO - mozversion platform_buildid: 20161114060943 08:33:27 INFO - mozversion platform_changeset: 458c900dd4ef310d5bffae1f2bb97da50839cc66 08:33:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:33:27 INFO - mozversion platform_version: 53.0a1 08:33:27 INFO - using testdate: 1479141207 08:33:27 INFO - actual date: 1479141207 08:33:27 INFO - starting webserver on 'localhost:49282' 08:33:27 INFO - SUITE-START | Running 2 tests 08:33:27 INFO - TEST-START | dromaeo_css 08:33:27 INFO - operating with platform_type : mac_ 08:33:27 INFO - Initialising browser for dromaeo_css test... 08:33:27 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpS_FXlr/profile http://localhost:49282/getInfo.html) 08:33:30 INFO - PROCESS | 1651 | 2016-11-14 08:33:30.052 plugin-container[1653:7816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9113, name = 'com.apple.tsm.portname' 08:33:30 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:30 INFO - PROCESS | 1651 | 2016-11-14 08:33:30.055 plugin-container[1653:7816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9503, name = 'com.apple.CFPasteboardClient' 08:33:30 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:30 INFO - PROCESS | 1651 | 2016-11-14 08:33:30.055 plugin-container[1653:7816] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:33:30 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:33:30 INFO - PROCESS | 1651 | colorDepth:24 08:33:30 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:33:30 INFO - PROCESS | 1651 | __metrics 08:33:30 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 08:33:30 INFO - TEST-INFO | 1651: exit 0 08:33:30 INFO - Browser initialized. 08:33:30 INFO - Running cycle 1/1 for dromaeo_css test... 08:33:30 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpS_FXlr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:33 INFO - PROCESS | 1654 | 2016-11-14 08:33:33.389 plugin-container[1655:7939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 08:33:33 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:33 INFO - PROCESS | 1654 | 2016-11-14 08:33:33.390 plugin-container[1655:7939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 08:33:33 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:33 INFO - PROCESS | 1654 | 2016-11-14 08:33:33.390 plugin-container[1655:7939] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:33:43 INFO - PROCESS | 1654 | RSS: Main: 237613056 08:33:43 INFO - PROCESS | 1654 | 08:34:32 INFO - PROCESS | 1654 | 1479141272657 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] 08:36:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49282/tests/dromaeo/cssquery-ext.html) 08:36:47 INFO - PROCESS | 1654 | RSS: Main: 252817408 08:36:47 INFO - PROCESS | 1654 | 08:39:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-ext.html (next: http://localhost:49282/tests/dromaeo/cssquery-jquery.html) 08:39:50 INFO - PROCESS | 1654 | RSS: Main: 264343552 08:39:50 INFO - PROCESS | 1654 | 08:42:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49282/tests/dromaeo/cssquery-mootools.html) 08:42:52 INFO - PROCESS | 1654 | RSS: Main: 267710464 08:42:52 INFO - PROCESS | 1654 | 08:45:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49282/tests/dromaeo/cssquery-prototype.html) 08:45:55 INFO - PROCESS | 1654 | RSS: Main: 267321344 08:45:55 INFO - PROCESS | 1654 | 08:48:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49282/tests/dromaeo/cssquery-yui.html) 08:48:58 INFO - PROCESS | 1654 | RSS: Main: 271724544 08:48:58 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-yui.html (next: http://localhost:49282/tests/dromaeo/cssquery-dojo.html) 08:52:01 INFO - PROCESS | 1654 | RSS: Main: 275722240 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | __start_tp_report 08:52:01 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:52:01 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:52:01 INFO - PROCESS | 1654 | |i|pagename|runs| 08:52:01 INFO - PROCESS | 1654 | |0;dojo.html;2514.5976643737004;2788.8047836651417;3328.38424891395;3364.209410788464;3395.100298420919;4505.43682039745;5329.600279979008;5570.136628822539;5604.551635869131;5612.326520817503;4343.913121737555;5592;5596.4123767004485;5672.943270567293;5702.77188912444;5010;5075.1647700624635;5085.79656813727;5117.078925793356;5143.4599367066485;5126.769295381717;5212.556932660728;5241.344831896013;5251.291075704788;5271.367435907678;5015;5090.032893750175;5147.613928955343;5176.197689358156;5195.168772996321;4209.97895010523;5141.820036298712;5152.098382782998;5154.278401023859;5163.1480805667015;4266.87199384019;5092.79628814847;5099.311592934943;5117.923231151536;5173.275741396207;4609.585137337622;5437.32033495813;5475;5483.122700367961;5500.477454641803;5992.4307190817235;6056.4851987581105;6080.14877917092;6101.450869421729;6174.96912515439;5223.6343455958095;6181.598196117238;6206.751729930797;6211.689415529206;6219.533534984849;5658.886822263573;5701.372848986608;5738.340090889601;5739.426057394311;5751.166080918285;6140.17107690465;6172.105044768573;6180.938190618036;6225.564210505221;6254.15568898202;6002.7298771554515;6047.969760151171;6058.727357268934;6065.272167339859;6071.332153463116;5985.730642121115;6090.116933044772;6175.660338681416;6178.598391104564;6198.690065496708;5654.717264136777;5910.290765108129;6007.909881351783;6044;6109.022556390956;5322.707251101149;6118.862843290023;6125.07994883275;6156.322804491502;6162.599431036968;4856.198727210041;4869.732164730974;4880.463149053601;4888.169011268093;4892.290617860462;4316.438862947797;4317.870463886088;4357.433533640607;4365.388845561561;4368.36658684489;4716.410448693913;5640.605157638925;5680.204771332017;5695.060315047971;5763.337216220105;6177.648598168165;6202.224721909762;6227.813165605039;6238.688065596702;6256.311805701369;5691.743871525791;5737.340205876294;5744.540436765049;5747.511461525817;5754.971225143848;5201.505856943584;5267.157254839208;5273.393559740679;5281.603879709037;5339.546138578265;6211.59624624407;6294.622322660563;6296.5277604178955;6310.2743184534365;6318.462930650762;4627.49097599257;6060.484858787053;6101.908471372844;6198.039303907902;6203.472704820141;5130.256112863689;5258.8948221036135;6178.7219575120125;6249.343818899132;6289.371062893674;5645.971770141123;6009.308929473166;6032.245969253843;6042.516598672184;6084.421979911901;5145.45972672864;5623.1846382273525;6189.597676151036;6204.503639708722;6253.437190652863;5954.225950626565;5990.281166260077;6001.099835024781;6007.939920600738;6016.939830601813;5643.927653745775;5833.445822646841;5865.794697185578;5990.880182396415;6047.455728984412;5699.686517241592;5712.514436272963;5820.388859169896;5831.241938547962;5870.765169393177;5777.45114214149;5819.82540523785;5860.736266867916;5875.706214689334;5920.230370051866;5788.160716696139;5859.062549991981;5893.852653683693;5904.202932604044;5922.763089476372 08:52:01 INFO - PROCESS | 1654 | |1;ext.html;462.3827190700415;465.52516433238077;502.1814442458792;526.3026489900881;538.4346436241945;36141;38602.8069859651;38612.227755444874;38653.03367415813;38683.41974870376;39467.80266098673;39970.40044399336;40027;40032;40033.799831000804;9202.355835091546;9351.438913665168;9370.437773733564;9402.717918462457;9411.152996230338;9306.302027347941;9335.299852511054;9386.20217281532;9397;9407.764805879873;9391.62433502659;9422.15200631943;9423.010583888696;9430.009848965863;9445.81108377832;9198.310126740522;9321.627134914595;9386.718398448058;9391.62433502659;9397.436153830791;8122.431429799916;8925.170340080267;9088.591013404346;9174.036726143791;9180.678676246365;37991.43012854809;38394.61605383938;38432.80783596092;38555;38636;3201.3117179806454;3274.1814546363407;3289.7039266465895;3335.916602084943;3396.558447401822;29505.852470737507;30020.799168033252;30190.49047547636;30198.54702179447;30370.088897333117;2445.8899349529315;3034.0897730680927;3118.844057797101;3119.5632611434417;3152.322250716107;426996;428000;494187;496766;497911.5104424491;55492;56672.899439050685;57023.872015998;57254;57389.852202955706;59274;59816.70091649601;59896.80206395848;59958;60074.699626501584;11865.762684746256;15249.313780879887;15445.613859653598;15536.766948495782;15549.533513994373;10063;10760.139188864872;10899.83650245247;11943.880561194277;12122.939385303194;13558.796618050737;14805.851941480663;15626.843731562767;15688.215589220721;15834.841651583336;5938.673372964528;6209.9379006210265;6490.350964903472;6650.3349665034075;6712.563683360566;771.9691212351555;799.3565180030132;852.7186028610544;889.7775556110972;946.9205106178962;24527;25956.61065084025;27772.30569235785;29430.852845736063;29476.852615737214;999.4503023337136;1007.2646967713611;1044.8276034454382;1084.6691759013577;1097.6048622495896;14461.349239284258;15007.099574025593;15131.092134471966;15241.933064685587;15293;673708;679547;679754;685168.574157126;685464;53993.460065398845;54640;54761;54798;54924.62688432821;1300.1873828856965;1322.5238913990956;1332.633525780418;1350.0212346049232;1351.047511504387;28070;28507.857460712563;29082.12753617395;29171.978980735574;29334.119976400743;52249.91000360095;53666;53713;56766.14850777324;57359.42640573541;631708;631871;636460;637667;638953;40280.79859600683;40686.77939661815;40948.181036379705;41006;41183.382249265684;44832.551674482835;44875.775621121684;45380.77309613431;45500.089998200514;46127;32946.835265823516;36851.81574092113;37419.62580374161;37627.62372376351;38463.230735385696;34092.829535852165;37253.81373093117;37257.6274237265;37894.62105378911;38194.85415437541;34832.95199041207;35208.295834083685;35530;36010;36919.26161476702;23439.414014649086;28741.70662320159;31853.36293274168;32152.35695286128;33205.66794332026;19695;24052.87973560121;24180;24452;24784.132555360477 08:52:01 INFO - PROCESS | 1654 | |2;jquery.html;372.7670206121173;375.3038114297977;383.40189304684696;384.9095462566298;403.96181812741264;18615.813841861578;19962.60074798503;20563;20726.27458038969;20770.273040443586;19325.613487730236;19668.016599170056;19844.702329465035;20170.991450427493;20224.39326820194;5265.420803711589;5536.446355364452;5666.348369937458;5927.970360148193;5973.133895585138;5794.942050579503;5854.063349864023;5925.111233314994;5934.258217722785;5938;5584.86037849055;5781.971090144543;5814.7092645367775;5856.238688970428;5887.61730487517;5270.578353731711;5543.750531226105;5668.659880407168;5702.971485142589;5820.854478638025;4525.094981003813;4868.513148685139;5403.729813509309;5446.618736688433;5485.945140548583;12713.93643031779;12940.352982350845;13597.592072237849;23738.881305593535;23900.521989560286;2069.865458745192;2098.8845613491253;2144.378130342199;2470.5429495543203;2554.846709197454;9465.66870159548;9480.573374198178;9700.660476883346;9933.35433196847;9935.205183585296;1759.357834390451;1810.6469238498498;1825.853931685462;1845.492489565367;1863.8415734662435;126441;127859;129529;130582.3470882649;135510.32244838812;11337.376444295644;11405.543778248943;11442.485088171052;11629.767404651859;11767.23512971654;14382.856171438361;14517.78223326651;14527.85472145286;14538.200398977946;14603.853961460249;8084.7574572762915;8158.510489370657;8268.049174344907;8360.205780450848;8368.079511253758;8009.399295052895;8019.558924259221;8050.59747012647;8159.714409995621;8286.171382861665;3575.249197668467;3820.751651142667;3945.861894833667;4204.810783514749;4516.593506584423;1638.3037209186048;1642.2117383655673;1659.1372486307293;1870.775506939176;1912.5792325688326;369.7615038300281;372.9216864458494;422.30530776872484;869.604330029835;874.8818909447143;10515.158787296998;10651.786964260826;11272.887271127183;11647.88352116468;12065.577704780291;406.1025134452892;418.9979050104791;428.2035414129716;429.30452666679554;433.58159376202116;5008.323876276729;5953.166556682068;5955.196048533479;6075.969620151959;6127.264728232641;612177;619186;624004.8799755973;625708;628968.9721719527;117430.41284793521;120301;120760;139316;139667;936.8782058332373;976.4531862157214;988.5600907596195;996.566493575297;998.1565577087495;18343.35798247055;18454.907725461286;18728.438146855617;18914.959677217885;18973.71539426882;36426.90719278426;39827.60172398239;41516.754497365124;41771.7911410441;55501.61245968722;77389;79102;79843.20156798357;80895;81450;6710.261871194067;7027.61348125858;7153.535020223669;7226.927730722626;7318.158411782713;8997.37018408705;9480.336376453582;12268.018558515476;12395.504179832706;12474.625761227178;10610.681679549625;10828.5127169276;10884.619038333936;10957.506912188812;11049.834252486058;9984.301098923006;10261.97380261968;10314.793704126025;10606.787864242826;10775.245732798629;9945.403275803475;10516.218957170848;11332;11423.600173993871;11449.313041217554;7155.606441645551;7339;7347.5224110432655;7352.301531354513;7545.773626790986;9050.728478145666;10895.182861285277;11133.274342476929;11155.888441115485;11160.720981975515 08:52:01 INFO - PROCESS | 1654 | |3;mootools.html;1480.1711041816584;1507.5251295841808;1583.8891277610571;1636.909969951653;1702.8807983441163;7527.698892044312;7782.299593036626;8108.432409731314;8550.871736923951;8551.315894728423;7968.12350641429;8270.131636178205;8274.007119145695;8282.42023058386;8303.045149807775;2293.4381076636205;2320.3271051395077;2323.4075310795806;2333.1717240379676;2347.0377145370317;2320.825938054644;2325.6278995360744;2361.173589243768;2377.940551486209;2391.473875747333;2303.7005189325364;2305.9538809223804;2320.8143348532076;2324.476992676651;2325.790678838904;2263.038208761278;2278.04322184682;2282.018731945263;2284.1091974130104;2295.885205739715;2275.2832857649787;2333.195047708538;2333.5216280662426;2336.6728657987896;2351.4591643921995;8384.119667434954;8411.495310281342;8461.873071903865;8482.36382271326;8485.787855303604;4846.321514987904;5012.1980483122525;5015.84952451427;5016.49835016499;5029.371328583927;3186.474231751758;3361.361261754326;3373.780704254216;3503.2818272254126;3509.4735789631504;958.8178246133282;995.85560093786;1030.9381437113798;1053.5733028123623;1055.3404122423485;9487.193588544982;10858.945705271422;10926.016658500694;10933.56265749369;10970.396628185446;4423.447069116361;4551.385562949025;4646.117237724821;4654.27858681898;4677.695949763254;9416.576254068583;9456.432614043179;9464.148226659634;9468.810623787624;9504.239660827117;5181.53366197044;5187.0922588546855;5201.5838732901275;5203.375594928632;5209.426963034063;3999.6200360965663;4030.2544029354663;4271.6369108625495;4391.451068616423;4741.193997020515;3097.7057179567905;3198.776085674027;3233.9029829105166;3806.2577797329254;3894.941575876364;1265.531753251303;1267.9302638354795;1270.2315099364937;1274.1272228523305;1293.5601895355624;729.5148726097086;764.9847003060019;818.382121498265;883.9867401988976;895.785011597212;5601.887962240732;5825.0679891217205;5858.677772722456;6007.519398448114;6076.088586712029;870.7518357268275;878.6397576993402;883.3154305413227;899.8290324838259;942.8821397325335;3118.610173728284;3727.4967879336486;3735.1782607826235;3834.769913805125;3842.3083844908183;9915.950420247853;10249.282550221413;10289.176865850714;10319.277650564389;10321.174306055498;10165.390076595428;10288.228382871315;10298.588056477656;10305.587776489007;10323.432211228304;7452.85094298111;7860.764177074697;8047.034355877215;8069.507141178783;8074.757757267292;5650.604457687922;5746;5756.942430575808;5855.23881895368;5866.853328666817;7810.9218907812465;9217.308701847522;9375.624975000923;9445.905540944777;9548.42709437436;8443.788905277417;9190.988991210828;9580.610811432443;9589.808203836024;9710.417374957526;6648.16897887764;6864.073350097673;7115.67979440916;7122.715091396493;7350.70597176107;7921.801954950942;8080.555569443502;8144;8146;8201.95899020501;7175.497715159889;7249.311315425026;7320.560766354036;7395.519291246052;7430.108386993596;6186.381361863958;7073.3987611053635;7127.46544009191;7135.286471352823;7301.853962920605;6789.558678685869;6794.966025169842;6876.415504682158;6878.8624227516175;6881.621510816954;4777.641676874179;4837.105135549935;4859.829905953275;4899.04468628614;4943.431505376784;4586.862394128181;4866.537678920497;4899.387576552931;4958.603311734981;4973.055119527278 08:52:01 INFO - PROCESS | 1654 | |4;prototype.html;306.59376326367556;307.2349848876298;313.68631368631367;317.7981981441784;318.4904153354631;5353.5984801139875;5409;5430.75561599728;5623.634463759853;5661.348944871336;4974;5259.658787009307;5259.86850328742;5456.263404440399;5464.672119672812;4027.859024934128;4184.3305071188615;4202.768847713374;4215.683823713219;4215.915681686364;3927.9018024549473;4022.296098182821;4071.6131967463043;4190.937135942963;4218.409422680827;4136.917261654765;4144.140543025318;4155;4156.376543518481;4157.875263742093;3458.170039190576;4150.522689890656;4151.5640857709805;4197.538270790211;4204.5375008749315;3640.6541378568995;3648.945265821015;3652.064405864896;3657.8902632921054;3669.7064234861145;4608.33179189019;5157.252198431244;5299.3905700844125;5327.273181330085;5329.6002799789785;4402.911941761146;4760.666753327268;4772.73749943753;4796.496367881357;4818.061486975843;5074.756684612294;5128.307678463397;5448.891022179534;5795.391483894173;5836.620619659709;3580.3018411409785;3912.4131380293184;3945.467361906163;3953.5848735882605;3953.644172024503;5651.519620832234;6159.538034647375;6188.195534580521;6218.481514986438;6239.438450539473;3261.6249131349723;3681.1533347330264;3758.8684396046556;3975.940360894589;4011.338129208706;3369.7809642373168;3761.962380376161;3955.980220098881;4051.4530538376944;4055.9391609125887;3991.3613821788376;4352.823588205897;4745.311929770164;4768.856934291976;4777.1878780606685;5174.826572839416;5483.013057649661;5911.261092363455;5913.438223368773;5991.7902873399225;1152.2395219155317;1241.7728818277537;1254.6424269083268;1260.9786073280673;1305.484333688188;791;833.3708050422014;883.7613844262013;885.2652298592153;897.438457084668;5219.3736751589295;5219.556337711261;5229.738513074331;5242.13504771716;5244.659097158673;4657;4859.1496488114435;5573.41479144692;5669.546436285087;5714.428557144252;4339.913201736011;4509.751963641966;5127.179651255781;5141.2288156776785;5229.660072095302;3004;3054.2364408897774;3055.1363668162403;3061.943629447837;3065.9233519161753;5128.512791284822;5275.366955965232;5324.163163708515;6007.339192688801;6084.908726369108;4901.681390709592;5420.560747663489;5805.390434004453;5957.642541447527;5970;6013.879722405527;6026.583752818085;6052.214596693898;6054.788082417094;6085.391460853879;5618.128652057626;5655.398516682372;5794.841811212983;5798.913016304675;5843.240378750906;5170.7156106413;5303.4431384705595;5695.319880635306;5699.406769919594;5716.314042314949;5107.259447380184;5930.229070220844;5961.314448838439;6017.216313031064;6052.546059045501;3348.9665103348657;3375.1730825947798;3596.2447885944243;3670.9904776186763;3728.4034554471154;5483.861294095385;5506.779728810803;5775.566832487664;5784.392638772868;5837.789215931136;4755.476897541199;4960.305557222064;5426.945730542644;5803.390643982321;5839;5055.292259083658;5554.888902221852;5800.970995145166;5848.970755146197;5871.266091738533;5397.271368365377;5675.744591493475;5697.287839020122;5704.4597958551785;5706.629069110494;5827.679477628772;5846.41535846412;5870.735816888165;5876.236089308362;5903.498202652822;4976.213320540509;5110.361204849394;5581.525570326406;5755.4460295720755;5764.971175144097 08:52:01 INFO - PROCESS | 1654 | |5;yui.html;630.8051457805592;653.3923451190391;685.5817951049862;715.7745310227278;727.9890801637977;12160.452779624917;13892.930535347321;14691.191984440864;15360.84639153608;16052.11713355765;14230.928845355784;14322.570322890304;14378.06542574732;14398.712025759478;14415.495457658984;801.63926233195;840.0423517190408;840.6637345061963;842.4818736477054;843.6498852976008;651.6937039591385;672.740994717035;808.7048227396987;825.2613910550054;829.2909562324216;856.7986523167051;874.8162885793959;875.1379890807549;876.0713643537838;876.7369789063256;849.8597731374316;869.2046777198856;869.5869462005528;870.1907226279558;871.3421366867974;637.0221709675626;645.2063961327547;646.2116218213772;647.5564238496597;647.653505374627;13695.452181912711;13890.58328250154;14259.714805703932;14260.358283877147;14294.85705142956;10582;12222.144449888512;12273.202241854251;12362.381880906009;12449.128561000734;10188.490575471196;10221.233407494468;10271.435071071162;10360.378377297386;10641.414722190277;3269.0683155300626;3280.114369120348;3323.202431416467;3353.6814002669707;3373.207296285371;88571;92096;92813;93040.53479732625;93549.79025943781;9363.578638961262;9409.529523523797;9431.339806213637;9458.810823783486;9702.563384647709;19283;19487.415377538695;19526.218951242074;19835.900820495805;19912.303069392503;7104.396126329217;7177.497575169688;7186.856262874713;7337.88993165103;7346.449016323797;3820.3887378019385;3929;3934.272159650474;3940.408938659224;3941.625545573166;3256.7557433192605;3338.2989572190113;3346.0129261867805;3359.462486002277;3360.2103505676146;579.3019411609001;589.6285340235625;590.9911351329733;593.2169536212159;603.7283222549783;575.168880967007;595.184597101973;604.1511676095093;613.4662843326334;613.7913109542777;8143.796405089802;8545.957270213734;8597.636970935764;8760.737177884674;8816.250618697355;472.6431544184119;597.1908064572528;600.9939900600938;606.8695230525458;612.6630353305665;3351.8994430167136;4056.452378928866;4137.2076569511455;4180.770057646798;4285.97857010713;94358.52820735853;98557;105586.94413055925;106159.89121957967;110591;80401;81797.18202818015;83477;83563;88001.55999220091;89314.10685893058;89773.55113224393;90207.19585608252;90573;90800.5459972709;8240.793980150544;8273.958630206811;8318.45930014547;8344.541050242295;8377.53923534187;44039.89900252519;52292.73853630707;53364.1995370062;55310.72344638251;57572.424275756704;54860.805567778436;55209.06768263091;55536.44463555474;71093.64453177701;80200.19799801927;9363.625454981997;11654.650360489199;12000;12110.455029523517;12342.382880855741;13191.80212296797;13382.063255572017;13469.191848489121;13537.052406331855;13921.58235252944;11960.76078478443;11974.760504790029;12131.514739410324;12436.19164754288;12475.812862807246;12253.528835198325;12291.754164916472;12673.619791406272;12734.68163295925;12987.28569928663;11864.169508134348;11884.762304753684;11974.401279935793;12429.192102513307;12473.814987576166;8511.787205319917;10436.791264174826;10442.425666588108;10552.050315471337;11025;9515.381500202464;10290.485475726333;10511.894881051092;10555.155587552823;10653.147748180281 08:52:01 INFO - PROCESS | 1654 | __end_tp_report 08:52:01 INFO - PROCESS | 1654 | __start_cc_report 08:52:01 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,367 08:52:01 INFO - PROCESS | 1654 | __end_cc_report 08:52:01 INFO - PROCESS | 1654 | __startTimestamp1479142321894__endTimestamp 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:52:01 INFO - PROCESS | 1654 | Number of tests: 6 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5565.29 Median:5744.54 stddev:689.98 (12.0%) stddev-sans-first:649.27 08:52:01 INFO - PROCESS | 1654 | Values: 2514.6 2788.8 3328.4 3364.2 3395.1 4505.4 5329.6 5570.1 5604.6 5612.3 4343.9 5592.0 5596.4 5672.9 5702.8 5010.0 5075.2 5085.8 5117.1 5143.5 5126.8 5212.6 5241.3 5251.3 5271.4 5015.0 5090.0 5147.6 5176.2 5195.2 4210.0 5141.8 5152.1 5154.3 5163.1 4266.9 5092.8 5099.3 5117.9 5173.3 4609.6 5437.3 5475.0 5483.1 5500.5 5992.4 6056.5 6080.1 6101.5 6175.0 5223.6 6181.6 6206.8 6211.7 6219.5 5658.9 5701.4 5738.3 5739.4 5751.2 6140.2 6172.1 6180.9 6225.6 6254.2 6002.7 6048.0 6058.7 6065.3 6071.3 5985.7 6090.1 6175.7 6178.6 6198.7 5654.7 5910.3 6007.9 6044.0 6109.0 5322.7 6118.9 6125.1 6156.3 6162.6 4856.2 4869.7 4880.5 4888.2 4892.3 4316.4 4317.9 4357.4 4365.4 4368.4 4716.4 5640.6 5680.2 5695.1 5763.3 6177.6 6202.2 6227.8 6238.7 6256.3 5691.7 5737.3 5744.5 5747.5 5755.0 5201.5 5267.2 5273.4 5281.6 5339.5 6211.6 6294.6 6296.5 6310.3 6318.5 4627.5 6060.5 6101.9 6198.0 6203.5 5130.3 5258.9 6178.7 6249.3 6289.4 5646.0 6009.3 6032.2 6042.5 6084.4 5145.5 5623.2 6189.6 6204.5 6253.4 5954.2 5990.3 6001.1 6007.9 6016.9 5643.9 5833.4 5865.8 5990.9 6047.5 5699.7 5712.5 5820.4 5831.2 5870.8 5777.5 5819.8 5860.7 5875.7 5920.2 5788.2 5859.1 5893.9 5904.2 5922.8 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:71697.71 Median:39998.70 stddev:161448.82 (403.6%) stddev-sans-first:161813.14 08:52:01 INFO - PROCESS | 1654 | Values: 462.4 465.5 502.2 526.3 538.4 36141.0 38602.8 38612.2 38653.0 38683.4 39467.8 39970.4 40027.0 40032.0 40033.8 9202.4 9351.4 9370.4 9402.7 9411.2 9306.3 9335.3 9386.2 9397.0 9407.8 9391.6 9422.2 9423.0 9430.0 9445.8 9198.3 9321.6 9386.7 9391.6 9397.4 8122.4 8925.2 9088.6 9174.0 9180.7 37991.4 38394.6 38432.8 38555.0 38636.0 3201.3 3274.2 3289.7 3335.9 3396.6 29505.9 30020.8 30190.5 30198.5 30370.1 2445.9 3034.1 3118.8 3119.6 3152.3 426996.0 428000.0 494187.0 496766.0 497911.5 55492.0 56672.9 57023.9 57254.0 57389.9 59274.0 59816.7 59896.8 59958.0 60074.7 11865.8 15249.3 15445.6 15536.8 15549.5 10063.0 10760.1 10899.8 11943.9 12122.9 13558.8 14805.9 15626.8 15688.2 15834.8 5938.7 6209.9 6490.4 6650.3 6712.6 772.0 799.4 852.7 889.8 946.9 24527.0 25956.6 27772.3 29430.9 29476.9 999.5 1007.3 1044.8 1084.7 1097.6 14461.3 15007.1 15131.1 15241.9 15293.0 673708.0 679547.0 679754.0 685168.6 685464.0 53993.5 54640.0 54761.0 54798.0 54924.6 1300.2 1322.5 1332.6 1350.0 1351.0 28070.0 28507.9 29082.1 29172.0 29334.1 52249.9 53666.0 53713.0 56766.1 57359.4 631708.0 631871.0 636460.0 637667.0 638953.0 40280.8 40686.8 40948.2 41006.0 41183.4 44832.6 44875.8 45380.8 45500.1 46127.0 32946.8 36851.8 37419.6 37627.6 38463.2 34092.8 37253.8 37257.6 37894.6 38194.9 34833.0 35208.3 35530.0 36010.0 36919.3 23439.4 28741.7 31853.4 32152.4 33205.7 19695.0 24052.9 24180.0 24452.0 24784.1 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34892.94 Median:20115.78 stddev:104065.17 (517.3%) stddev-sans-first:104324.82 08:52:01 INFO - PROCESS | 1654 | Values: 372.8 375.3 383.4 384.9 404.0 18615.8 19962.6 20563.0 20726.3 20770.3 19325.6 19668.0 19844.7 20171.0 20224.4 5265.4 5536.4 5666.3 5928.0 5973.1 5794.9 5854.1 5925.1 5934.3 5938.0 5584.9 5782.0 5814.7 5856.2 5887.6 5270.6 5543.8 5668.7 5703.0 5820.9 4525.1 4868.5 5403.7 5446.6 5485.9 12713.9 12940.4 13597.6 23738.9 23900.5 2069.9 2098.9 2144.4 2470.5 2554.8 9465.7 9480.6 9700.7 9933.4 9935.2 1759.4 1810.6 1825.9 1845.5 1863.8 126441.0 127859.0 129529.0 130582.3 135510.3 11337.4 11405.5 11442.5 11629.8 11767.2 14382.9 14517.8 14527.9 14538.2 14603.9 8084.8 8158.5 8268.0 8360.2 8368.1 8009.4 8019.6 8050.6 8159.7 8286.2 3575.2 3820.8 3945.9 4204.8 4516.6 1638.3 1642.2 1659.1 1870.8 1912.6 369.8 372.9 422.3 869.6 874.9 10515.2 10651.8 11272.9 11647.9 12065.6 406.1 419.0 428.2 429.3 433.6 5008.3 5953.2 5955.2 6076.0 6127.3 612177.0 619186.0 624004.9 625708.0 628969.0 117430.4 120301.0 120760.0 139316.0 139667.0 936.9 976.5 988.6 996.6 998.2 18343.4 18454.9 18728.4 18915.0 18973.7 36426.9 39827.6 41516.8 41771.8 55501.6 77389.0 79102.0 79843.2 80895.0 81450.0 6710.3 7027.6 7153.5 7226.9 7318.2 8997.4 9480.3 12268.0 12395.5 12474.6 10610.7 10828.5 10884.6 10957.5 11049.8 9984.3 10262.0 10314.8 10606.8 10775.2 9945.4 10516.2 11332.0 11423.6 11449.3 7155.6 7339.0 7347.5 7352.3 7545.8 9050.7 10895.2 11133.3 11155.9 11160.7 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5444.18 Median:5105.45 stddev:3007.50 (58.9%) stddev-sans-first:3001.19 08:52:01 INFO - PROCESS | 1654 | Values: 1480.2 1507.5 1583.9 1636.9 1702.9 7527.7 7782.3 8108.4 8550.9 8551.3 7968.1 8270.1 8274.0 8282.4 8303.0 2293.4 2320.3 2323.4 2333.2 2347.0 2320.8 2325.6 2361.2 2377.9 2391.5 2303.7 2306.0 2320.8 2324.5 2325.8 2263.0 2278.0 2282.0 2284.1 2295.9 2275.3 2333.2 2333.5 2336.7 2351.5 8384.1 8411.5 8461.9 8482.4 8485.8 4846.3 5012.2 5015.8 5016.5 5029.4 3186.5 3361.4 3373.8 3503.3 3509.5 958.8 995.9 1030.9 1053.6 1055.3 9487.2 10858.9 10926.0 10933.6 10970.4 4423.4 4551.4 4646.1 4654.3 4677.7 9416.6 9456.4 9464.1 9468.8 9504.2 5181.5 5187.1 5201.6 5203.4 5209.4 3999.6 4030.3 4271.6 4391.5 4741.2 3097.7 3198.8 3233.9 3806.3 3894.9 1265.5 1267.9 1270.2 1274.1 1293.6 729.5 765.0 818.4 884.0 895.8 5601.9 5825.1 5858.7 6007.5 6076.1 870.8 878.6 883.3 899.8 942.9 3118.6 3727.5 3735.2 3834.8 3842.3 9916.0 10249.3 10289.2 10319.3 10321.2 10165.4 10288.2 10298.6 10305.6 10323.4 7452.9 7860.8 8047.0 8069.5 8074.8 5650.6 5746.0 5756.9 5855.2 5866.9 7810.9 9217.3 9375.6 9445.9 9548.4 8443.8 9191.0 9580.6 9589.8 9710.4 6648.2 6864.1 7115.7 7122.7 7350.7 7921.8 8080.6 8144.0 8146.0 8202.0 7175.5 7249.3 7320.6 7395.5 7430.1 6186.4 7073.4 7127.5 7135.3 7301.9 6789.6 6795.0 6876.4 6878.9 6881.6 4777.6 4837.1 4859.8 4899.0 4943.4 4586.9 4866.5 4899.4 4958.6 4973.1 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4617.70 Median:5197.10 stddev:1432.59 (27.6%) stddev-sans-first:1399.59 08:52:01 INFO - PROCESS | 1654 | Values: 306.6 307.2 313.7 317.8 318.5 5353.6 5409.0 5430.8 5623.6 5661.3 4974.0 5259.7 5259.9 5456.3 5464.7 4027.9 4184.3 4202.8 4215.7 4215.9 3927.9 4022.3 4071.6 4190.9 4218.4 4136.9 4144.1 4155.0 4156.4 4157.9 3458.2 4150.5 4151.6 4197.5 4204.5 3640.7 3648.9 3652.1 3657.9 3669.7 4608.3 5157.3 5299.4 5327.3 5329.6 4402.9 4760.7 4772.7 4796.5 4818.1 5074.8 5128.3 5448.9 5795.4 5836.6 3580.3 3912.4 3945.5 3953.6 3953.6 5651.5 6159.5 6188.2 6218.5 6239.4 3261.6 3681.2 3758.9 3975.9 4011.3 3369.8 3762.0 3956.0 4051.5 4055.9 3991.4 4352.8 4745.3 4768.9 4777.2 5174.8 5483.0 5911.3 5913.4 5991.8 1152.2 1241.8 1254.6 1261.0 1305.5 791.0 833.4 883.8 885.3 897.4 5219.4 5219.6 5229.7 5242.1 5244.7 4657.0 4859.1 5573.4 5669.5 5714.4 4339.9 4509.8 5127.2 5141.2 5229.7 3004.0 3054.2 3055.1 3061.9 3065.9 5128.5 5275.4 5324.2 6007.3 6084.9 4901.7 5420.6 5805.4 5957.6 5970.0 6013.9 6026.6 6052.2 6054.8 6085.4 5618.1 5655.4 5794.8 5798.9 5843.2 5170.7 5303.4 5695.3 5699.4 5716.3 5107.3 5930.2 5961.3 6017.2 6052.5 3349.0 3375.2 3596.2 3671.0 3728.4 5483.9 5506.8 5775.6 5784.4 5837.8 4755.5 4960.3 5426.9 5803.4 5839.0 5055.3 5554.9 5801.0 5849.0 5871.3 5397.3 5675.7 5697.3 5704.5 5706.6 5827.7 5846.4 5870.7 5876.2 5903.5 4976.2 5110.4 5581.5 5755.4 5765.0 08:52:01 INFO - PROCESS | 1654 | 08:52:01 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:19619.75 Median:55259.90 stddev:28869.92 (52.2%) stddev-sans-first:28915.70 08:52:01 INFO - PROCESS | 1654 | Values: 630.8 653.4 685.6 715.8 728.0 12160.5 13892.9 14691.2 15360.8 16052.1 14230.9 14322.6 14378.1 14398.7 14415.5 801.6 840.0 840.7 842.5 843.6 651.7 672.7 808.7 825.3 829.3 856.8 874.8 875.1 876.1 876.7 849.9 869.2 869.6 870.2 871.3 637.0 645.2 646.2 647.6 647.7 13695.5 13890.6 14259.7 14260.4 14294.9 10582.0 12222.1 12273.2 12362.4 12449.1 10188.5 10221.2 10271.4 10360.4 10641.4 3269.1 3280.1 3323.2 3353.7 3373.2 88571.0 92096.0 92813.0 93040.5 93549.8 9363.6 9409.5 9431.3 9458.8 9702.6 19283.0 19487.4 19526.2 19835.9 19912.3 7104.4 7177.5 7186.9 7337.9 7346.4 3820.4 3929.0 3934.3 3940.4 3941.6 3256.8 3338.3 3346.0 3359.5 3360.2 579.3 589.6 591.0 593.2 603.7 575.2 595.2 604.2 613.5 613.8 8143.8 8546.0 8597.6 8760.7 8816.3 472.6 597.2 601.0 606.9 612.7 3351.9 4056.5 4137.2 4180.8 4286.0 94358.5 98557.0 105586.9 106159.9 110591.0 80401.0 81797.2 83477.0 83563.0 88001.6 89314.1 89773.6 90207.2 90573.0 90800.5 8240.8 8274.0 8318.5 8344.5 8377.5 44039.9 52292.7 53364.2 55310.7 57572.4 54860.8 55209.1 55536.4 71093.6 80200.2 9363.6 11654.7 12000.0 12110.5 12342.4 13191.8 13382.1 13469.2 13537.1 13921.6 11960.8 11974.8 12131.5 12436.2 12475.8 12253.5 12291.8 12673.6 12734.7 12987.3 11864.2 11884.8 11974.4 12429.2 12473.8 8511.8 10436.8 10442.4 10552.1 11025.0 9515.4 10290.5 10511.9 10555.2 10653.1 08:52:01 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:52:01 INFO - PROCESS | 1654 | 08:52:02 INFO - TEST-INFO | 1654: exit 0 08:52:02 INFO - TEST-OK | dromaeo_css | took 1115387ms 08:52:02 INFO - TEST-START | kraken 08:52:02 INFO - operating with platform_type : mac_ 08:52:02 INFO - Initialising browser for kraken test... 08:52:02 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp_erU9k/profile http://localhost:49282/getInfo.html) 08:52:05 INFO - PROCESS | 1665 | 2016-11-14 08:52:05.329 plugin-container[1666:10838] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 08:52:05 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:52:05 INFO - PROCESS | 1665 | 2016-11-14 08:52:05.332 plugin-container[1666:10838] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:52:05 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:52:05 INFO - PROCESS | 1665 | 2016-11-14 08:52:05.333 plugin-container[1666:10838] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:52:05 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 08:52:05 INFO - PROCESS | 1665 | colorDepth:24 08:52:05 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 08:52:05 INFO - PROCESS | 1665 | __metrics 08:52:05 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 08:52:06 INFO - TEST-INFO | 1665: exit 0 08:52:06 INFO - Browser initialized. 08:52:06 INFO - Running cycle 1/1 for kraken test... 08:52:06 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp_erU9k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:08 INFO - PROCESS | 1667 | 2016-11-14 08:52:08.934 plugin-container[1668:10959] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 08:52:08 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:52:08 INFO - PROCESS | 1667 | 2016-11-14 08:52:08.934 plugin-container[1668:10959] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:52:08 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:52:08 INFO - PROCESS | 1667 | 2016-11-14 08:52:08.934 plugin-container[1668:10959] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:52:18 INFO - PROCESS | 1667 | RSS: Main: 237760512 08:52:18 INFO - PROCESS | 1667 | 08:52:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection) 08:52:22 INFO - PROCESS | 1667 | RSS: Main: 250294272 08:52:22 INFO - PROCESS | 1667 | 08:52:25 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-dft) 08:52:25 INFO - PROCESS | 1667 | RSS: Main: 258056192 08:52:25 INFO - PROCESS | 1667 | 08:52:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-fft) 08:52:29 INFO - PROCESS | 1667 | RSS: Main: 260980736 08:52:29 INFO - PROCESS | 1667 | 08:52:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator) 08:52:32 INFO - PROCESS | 1667 | RSS: Main: 262983680 08:52:32 INFO - PROCESS | 1667 | 08:52:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:35 INFO - PROCESS | 1667 | RSS: Main: 263249920 08:52:35 INFO - PROCESS | 1667 | 08:52:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:38 INFO - PROCESS | 1667 | RSS: Main: 269303808 08:52:38 INFO - PROCESS | 1667 | 08:52:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate) 08:52:42 INFO - PROCESS | 1667 | RSS: Main: 268578816 08:52:42 INFO - PROCESS | 1667 | 08:52:45 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial) 08:52:45 INFO - PROCESS | 1667 | RSS: Main: 269164544 08:52:45 INFO - PROCESS | 1667 | 08:52:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:52:47 INFO - PROCESS | 1667 | RSS: Main: 268259328 08:52:47 INFO - PROCESS | 1667 | 08:52:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:52:50 INFO - PROCESS | 1667 | RSS: Main: 268353536 08:52:50 INFO - PROCESS | 1667 | 08:52:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:53 INFO - PROCESS | 1667 | RSS: Main: 268263424 08:52:53 INFO - PROCESS | 1667 | 08:52:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:57 INFO - PROCESS | 1667 | RSS: Main: 268382208 08:52:57 INFO - PROCESS | 1667 | 08:53:00 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:53:00 INFO - PROCESS | 1667 | RSS: Main: 268521472 08:53:00 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49282/tests/kraken/driver.html?testName=ai-astar) 08:53:02 INFO - PROCESS | 1667 | RSS: Main: 270024704 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | __start_tp_report 08:53:02 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 08:53:02 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 08:53:02 INFO - PROCESS | 1667 | |i|pagename|runs| 08:53:02 INFO - PROCESS | 1667 | |0;ai-astar;113;116;118;117;122;145;172;168;116;117 08:53:02 INFO - PROCESS | 1667 | |1;audio-beat-detection;147;113;101;186;153;137;115;105;178;120 08:53:02 INFO - PROCESS | 1667 | |2;audio-dft;208;206;246;199;197;196;236;276;233;195 08:53:02 INFO - PROCESS | 1667 | |3;audio-fft;84;59;59;84;61;52;68;82;106;57 08:53:02 INFO - PROCESS | 1667 | |4;audio-oscillator;86;79;106;96;100;103;119;70;70;88 08:53:02 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;89;86;88;102;101;86;78;132;138;86 08:53:02 INFO - PROCESS | 1667 | |6;imaging-darkroom;100;100;99;100;101;100;100;100;103;100 08:53:02 INFO - PROCESS | 1667 | |7;imaging-desaturate;89;100;86;88;93;89;121;98;74;120 08:53:02 INFO - PROCESS | 1667 | |8;json-parse-financial;57;56;57;57;57;57;57;64;78;85 08:53:02 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;66;75;74;80;74;69;72;73;72;74 08:53:02 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;87;91;90;90;89;89;87;89;91;92 08:53:02 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;155;158;147;149;147;145;146;175;222;178 08:53:02 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;175;175;175;175;177;259;241;188;176;175 08:53:02 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;68;70;67;67;68;69;69;69;68;69 08:53:02 INFO - PROCESS | 1667 | __end_tp_report 08:53:02 INFO - PROCESS | 1667 | __start_cc_report 08:53:02 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,975 08:53:02 INFO - PROCESS | 1667 | __end_cc_report 08:53:02 INFO - PROCESS | 1667 | __startTimestamp1479142382827__endTimestamp 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | ------- Summary: start ------- 08:53:02 INFO - PROCESS | 1667 | Number of tests: 14 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:130.40 Median:120.00 stddev:22.75 (19.0%) stddev-sans-first:23.24 08:53:02 INFO - PROCESS | 1667 | Values: 113.0 116.0 118.0 117.0 122.0 145.0 172.0 168.0 116.0 117.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:142.00 stddev:29.97 (21.1%) stddev-sans-first:31.50 08:53:02 INFO - PROCESS | 1667 | Values: 147.0 113.0 101.0 186.0 153.0 137.0 115.0 105.0 178.0 120.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:219.20 Median:220.50 stddev:27.37 (12.4%) stddev-sans-first:28.73 08:53:02 INFO - PROCESS | 1667 | Values: 208.0 206.0 246.0 199.0 197.0 196.0 236.0 276.0 233.0 195.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:71.20 Median:64.50 stddev:17.12 (26.5%) stddev-sans-first:17.52 08:53:02 INFO - PROCESS | 1667 | Values: 84.0 59.0 59.0 84.0 61.0 52.0 68.0 82.0 106.0 57.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:74.50 stddev:16.04 (21.5%) stddev-sans-first:16.87 08:53:02 INFO - PROCESS | 1667 | Values: 86.0 79.0 106.0 96.0 100.0 103.0 119.0 70.0 70.0 88.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:86.00 stddev:20.52 (23.9%) stddev-sans-first:21.47 08:53:02 INFO - PROCESS | 1667 | Values: 89.0 86.0 88.0 102.0 101.0 86.0 78.0 132.0 138.0 86.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:100.00 stddev:1.06 (1.1%) stddev-sans-first:1.12 08:53:02 INFO - PROCESS | 1667 | Values: 100.0 100.0 99.0 100.0 101.0 100.0 100.0 100.0 103.0 100.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:88.50 stddev:14.82 (16.7%) stddev-sans-first:15.51 08:53:02 INFO - PROCESS | 1667 | Values: 89.0 100.0 86.0 88.0 93.0 89.0 121.0 98.0 74.0 120.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:57.00 stddev:10.39 (18.2%) stddev-sans-first:10.83 08:53:02 INFO - PROCESS | 1667 | Values: 57.0 56.0 57.0 57.0 57.0 57.0 57.0 64.0 78.0 85.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:74.00 stddev:3.70 (5.0%) stddev-sans-first:2.96 08:53:02 INFO - PROCESS | 1667 | Values: 66.0 75.0 74.0 80.0 74.0 69.0 72.0 73.0 72.0 74.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:90.00 stddev:1.65 (1.8%) stddev-sans-first:1.48 08:53:02 INFO - PROCESS | 1667 | Values: 87.0 91.0 90.0 90.0 89.0 89.0 87.0 89.0 91.0 92.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:162.20 Median:156.50 stddev:24.16 (15.4%) stddev-sans-first:25.49 08:53:02 INFO - PROCESS | 1667 | Values: 155.0 158.0 147.0 149.0 147.0 145.0 146.0 175.0 222.0 178.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:176.50 stddev:31.32 (17.7%) stddev-sans-first:32.64 08:53:02 INFO - PROCESS | 1667 | Values: 175.0 175.0 175.0 175.0 177.0 259.0 241.0 188.0 176.0 175.0 08:53:02 INFO - PROCESS | 1667 | 08:53:02 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 08:53:02 INFO - PROCESS | 1667 | Values: 68.0 70.0 67.0 67.0 68.0 69.0 69.0 69.0 68.0 69.0 08:53:02 INFO - PROCESS | 1667 | -------- Summary: end -------- 08:53:02 INFO - PROCESS | 1667 | 08:53:03 INFO - TEST-INFO | 1667: exit 0 08:53:03 INFO - TEST-OK | kraken | took 61139ms 08:53:03 INFO - SUITE-END | took 1176s 08:53:03 INFO - Completed test suite (00:19:36) 08:53:03 INFO - javascript benchmark 08:53:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6974.343235972068, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2514.5976643737004, 2788.8047836651417, 3328.38424891395, 3364.209410788464, 3395.100298420919, 4505.43682039745, 5329.600279979008, 5570.136628822539, 5604.551635869131, 5612.326520817503, 4343.913121737555, 5592.0, 5596.4123767004485, 5672.943270567293, 5702.77188912444, 5010.0, 5075.1647700624635, 5085.79656813727, 5117.078925793356, 5143.4599367066485, 5126.769295381717, 5212.556932660728, 5241.344831896013, 5251.291075704788, 5271.367435907678, 5015.0, 5090.032893750175, 5147.613928955343, 5176.197689358156, 5195.168772996321, 4209.97895010523, 5141.820036298712, 5152.098382782998, 5154.278401023859, 5163.1480805667015, 4266.87199384019, 5092.79628814847, 5099.311592934943, 5117.923231151536, 5173.275741396207, 4609.585137337622, 5437.32033495813, 5475.0, 5483.122700367961, 5500.477454641803, 5992.4307190817235, 6056.4851987581105, 6080.14877917092, 6101.450869421729, 6174.96912515439, 5223.6343455958095, 6181.598196117238, 6206.751729930797, 6211.689415529206, 6219.533534984849, 5658.886822263573, 5701.372848986608, 5738.340090889601, 5739.426057394311, 5751.166080918285, 6140.17107690465, 6172.105044768573, 6180.938190618036, 6225.564210505221, 6254.15568898202, 6002.7298771554515, 6047.969760151171, 6058.727357268934, 6065.272167339859, 6071.332153463116, 5985.730642121115, 6090.116933044772, 6175.660338681416, 6178.598391104564, 6198.690065496708, 5654.717264136777, 5910.290765108129, 6007.909881351783, 6044.0, 6109.022556390956, 5322.707251101149, 6118.862843290023, 6125.07994883275, 6156.322804491502, 6162.599431036968, 4856.198727210041, 4869.732164730974, 4880.463149053601, 4888.169011268093, 4892.290617860462, 4316.438862947797, 4317.870463886088, 4357.433533640607, 4365.388845561561, 4368.36658684489, 4716.410448693913, 5640.605157638925, 5680.204771332017, 5695.060315047971, 5763.337216220105, 6177.648598168165, 6202.224721909762, 6227.813165605039, 6238.688065596702, 6256.311805701369, 5691.743871525791, 5737.340205876294, 5744.540436765049, 5747.511461525817, 5754.971225143848, 5201.505856943584, 5267.157254839208, 5273.393559740679, 5281.603879709037, 5339.546138578265, 6211.59624624407, 6294.622322660563, 6296.5277604178955, 6310.2743184534365, 6318.462930650762, 4627.49097599257, 6060.484858787053, 6101.908471372844, 6198.039303907902, 6203.472704820141, 5130.256112863689, 5258.8948221036135, 6178.7219575120125, 6249.343818899132, 6289.371062893674, 5645.971770141123, 6009.308929473166, 6032.245969253843, 6042.516598672184, 6084.421979911901, 5145.45972672864, 5623.1846382273525, 6189.597676151036, 6204.503639708722, 6253.437190652863, 5954.225950626565, 5990.281166260077, 6001.099835024781, 6007.939920600738, 6016.939830601813, 5643.927653745775, 5833.445822646841, 5865.794697185578, 5990.880182396415, 6047.455728984412, 5699.686517241592, 5712.514436272963, 5820.388859169896, 5831.241938547962, 5870.765169393177, 5777.45114214149, 5819.82540523785, 5860.736266867916, 5875.706214689334, 5920.230370051866, 5788.160716696139, 5859.062549991981, 5893.852653683693, 5904.202932604044, 5922.763089476372], "value": 5521.050280258099, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.3827190700415, 465.52516433238077, 502.1814442458792, 526.3026489900881, 538.4346436241945, 36141.0, 38602.8069859651, 38612.227755444874, 38653.03367415813, 38683.41974870376, 39467.80266098673, 39970.40044399336, 40027.0, 40032.0, 40033.799831000804, 9202.355835091546, 9351.438913665168, 9370.437773733564, 9402.717918462457, 9411.152996230338, 9306.302027347941, 9335.299852511054, 9386.20217281532, 9397.0, 9407.764805879873, 9391.62433502659, 9422.15200631943, 9423.010583888696, 9430.009848965863, 9445.81108377832, 9198.310126740522, 9321.627134914595, 9386.718398448058, 9391.62433502659, 9397.436153830791, 8122.431429799916, 8925.170340080267, 9088.591013404346, 9174.036726143791, 9180.678676246365, 37991.43012854809, 38394.61605383938, 38432.80783596092, 38555.0, 38636.0, 3201.3117179806454, 3274.1814546363407, 3289.7039266465895, 3335.916602084943, 3396.558447401822, 29505.852470737507, 30020.799168033252, 30190.49047547636, 30198.54702179447, 30370.088897333117, 2445.8899349529315, 3034.0897730680927, 3118.844057797101, 3119.5632611434417, 3152.322250716107, 426996.0, 428000.0, 494187.0, 496766.0, 497911.5104424491, 55492.0, 56672.899439050685, 57023.872015998, 57254.0, 57389.852202955706, 59274.0, 59816.70091649601, 59896.80206395848, 59958.0, 60074.699626501584, 11865.762684746256, 15249.313780879887, 15445.613859653598, 15536.766948495782, 15549.533513994373, 10063.0, 10760.139188864872, 10899.83650245247, 11943.880561194277, 12122.939385303194, 13558.796618050737, 14805.851941480663, 15626.843731562767, 15688.215589220721, 15834.841651583336, 5938.673372964528, 6209.9379006210265, 6490.350964903472, 6650.3349665034075, 6712.563683360566, 771.9691212351555, 799.3565180030132, 852.7186028610544, 889.7775556110972, 946.9205106178962, 24527.0, 25956.61065084025, 27772.30569235785, 29430.852845736063, 29476.852615737214, 999.4503023337136, 1007.2646967713611, 1044.8276034454382, 1084.6691759013577, 1097.6048622495896, 14461.349239284258, 15007.099574025593, 15131.092134471966, 15241.933064685587, 15293.0, 673708.0, 679547.0, 679754.0, 685168.574157126, 685464.0, 53993.460065398845, 54640.0, 54761.0, 54798.0, 54924.62688432821, 1300.1873828856965, 1322.5238913990956, 1332.633525780418, 1350.0212346049232, 1351.047511504387, 28070.0, 28507.857460712563, 29082.12753617395, 29171.978980735574, 29334.119976400743, 52249.91000360095, 53666.0, 53713.0, 56766.14850777324, 57359.42640573541, 631708.0, 631871.0, 636460.0, 637667.0, 638953.0, 40280.79859600683, 40686.77939661815, 40948.181036379705, 41006.0, 41183.382249265684, 44832.551674482835, 44875.775621121684, 45380.77309613431, 45500.089998200514, 46127.0, 32946.835265823516, 36851.81574092113, 37419.62580374161, 37627.62372376351, 38463.230735385696, 34092.829535852165, 37253.81373093117, 37257.6274237265, 37894.62105378911, 38194.85415437541, 34832.95199041207, 35208.295834083685, 35530.0, 36010.0, 36919.26161476702, 23439.414014649086, 28741.70662320159, 31853.36293274168, 32152.35695286128, 33205.66794332026, 19695.0, 24052.87973560121, 24180.0, 24452.0, 24784.132555360477], "value": 19243.263108796975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7670206121173, 375.3038114297977, 383.40189304684696, 384.9095462566298, 403.96181812741264, 18615.813841861578, 19962.60074798503, 20563.0, 20726.27458038969, 20770.273040443586, 19325.613487730236, 19668.016599170056, 19844.702329465035, 20170.991450427493, 20224.39326820194, 5265.420803711589, 5536.446355364452, 5666.348369937458, 5927.970360148193, 5973.133895585138, 5794.942050579503, 5854.063349864023, 5925.111233314994, 5934.258217722785, 5938.0, 5584.86037849055, 5781.971090144543, 5814.7092645367775, 5856.238688970428, 5887.61730487517, 5270.578353731711, 5543.750531226105, 5668.659880407168, 5702.971485142589, 5820.854478638025, 4525.094981003813, 4868.513148685139, 5403.729813509309, 5446.618736688433, 5485.945140548583, 12713.93643031779, 12940.352982350845, 13597.592072237849, 23738.881305593535, 23900.521989560286, 2069.865458745192, 2098.8845613491253, 2144.378130342199, 2470.5429495543203, 2554.846709197454, 9465.66870159548, 9480.573374198178, 9700.660476883346, 9933.35433196847, 9935.205183585296, 1759.357834390451, 1810.6469238498498, 1825.853931685462, 1845.492489565367, 1863.8415734662435, 126441.0, 127859.0, 129529.0, 130582.3470882649, 135510.32244838812, 11337.376444295644, 11405.543778248943, 11442.485088171052, 11629.767404651859, 11767.23512971654, 14382.856171438361, 14517.78223326651, 14527.85472145286, 14538.200398977946, 14603.853961460249, 8084.7574572762915, 8158.510489370657, 8268.049174344907, 8360.205780450848, 8368.079511253758, 8009.399295052895, 8019.558924259221, 8050.59747012647, 8159.714409995621, 8286.171382861665, 3575.249197668467, 3820.751651142667, 3945.861894833667, 4204.810783514749, 4516.593506584423, 1638.3037209186048, 1642.2117383655673, 1659.1372486307293, 1870.775506939176, 1912.5792325688326, 369.7615038300281, 372.9216864458494, 422.30530776872484, 869.604330029835, 874.8818909447143, 10515.158787296998, 10651.786964260826, 11272.887271127183, 11647.88352116468, 12065.577704780291, 406.1025134452892, 418.9979050104791, 428.2035414129716, 429.30452666679554, 433.58159376202116, 5008.323876276729, 5953.166556682068, 5955.196048533479, 6075.969620151959, 6127.264728232641, 612177.0, 619186.0, 624004.8799755973, 625708.0, 628968.9721719527, 117430.41284793521, 120301.0, 120760.0, 139316.0, 139667.0, 936.8782058332373, 976.4531862157214, 988.5600907596195, 996.566493575297, 998.1565577087495, 18343.35798247055, 18454.907725461286, 18728.438146855617, 18914.959677217885, 18973.71539426882, 36426.90719278426, 39827.60172398239, 41516.754497365124, 41771.7911410441, 55501.61245968722, 77389.0, 79102.0, 79843.20156798357, 80895.0, 81450.0, 6710.261871194067, 7027.61348125858, 7153.535020223669, 7226.927730722626, 7318.158411782713, 8997.37018408705, 9480.336376453582, 12268.018558515476, 12395.504179832706, 12474.625761227178, 10610.681679549625, 10828.5127169276, 10884.619038333936, 10957.506912188812, 11049.834252486058, 9984.301098923006, 10261.97380261968, 10314.793704126025, 10606.787864242826, 10775.245732798629, 9945.403275803475, 10516.218957170848, 11332.0, 11423.600173993871, 11449.313041217554, 7155.606441645551, 7339.0, 7347.5224110432655, 7352.301531354513, 7545.773626790986, 9050.728478145666, 10895.182861285277, 11133.274342476929, 11155.888441115485, 11160.720981975515], "value": 8561.695293765893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.1711041816584, 1507.5251295841808, 1583.8891277610571, 1636.909969951653, 1702.8807983441163, 7527.698892044312, 7782.299593036626, 8108.432409731314, 8550.871736923951, 8551.315894728423, 7968.12350641429, 8270.131636178205, 8274.007119145695, 8282.42023058386, 8303.045149807775, 2293.4381076636205, 2320.3271051395077, 2323.4075310795806, 2333.1717240379676, 2347.0377145370317, 2320.825938054644, 2325.6278995360744, 2361.173589243768, 2377.940551486209, 2391.473875747333, 2303.7005189325364, 2305.9538809223804, 2320.8143348532076, 2324.476992676651, 2325.790678838904, 2263.038208761278, 2278.04322184682, 2282.018731945263, 2284.1091974130104, 2295.885205739715, 2275.2832857649787, 2333.195047708538, 2333.5216280662426, 2336.6728657987896, 2351.4591643921995, 8384.119667434954, 8411.495310281342, 8461.873071903865, 8482.36382271326, 8485.787855303604, 4846.321514987904, 5012.1980483122525, 5015.84952451427, 5016.49835016499, 5029.371328583927, 3186.474231751758, 3361.361261754326, 3373.780704254216, 3503.2818272254126, 3509.4735789631504, 958.8178246133282, 995.85560093786, 1030.9381437113798, 1053.5733028123623, 1055.3404122423485, 9487.193588544982, 10858.945705271422, 10926.016658500694, 10933.56265749369, 10970.396628185446, 4423.447069116361, 4551.385562949025, 4646.117237724821, 4654.27858681898, 4677.695949763254, 9416.576254068583, 9456.432614043179, 9464.148226659634, 9468.810623787624, 9504.239660827117, 5181.53366197044, 5187.0922588546855, 5201.5838732901275, 5203.375594928632, 5209.426963034063, 3999.6200360965663, 4030.2544029354663, 4271.6369108625495, 4391.451068616423, 4741.193997020515, 3097.7057179567905, 3198.776085674027, 3233.9029829105166, 3806.2577797329254, 3894.941575876364, 1265.531753251303, 1267.9302638354795, 1270.2315099364937, 1274.1272228523305, 1293.5601895355624, 729.5148726097086, 764.9847003060019, 818.382121498265, 883.9867401988976, 895.785011597212, 5601.887962240732, 5825.0679891217205, 5858.677772722456, 6007.519398448114, 6076.088586712029, 870.7518357268275, 878.6397576993402, 883.3154305413227, 899.8290324838259, 942.8821397325335, 3118.610173728284, 3727.4967879336486, 3735.1782607826235, 3834.769913805125, 3842.3083844908183, 9915.950420247853, 10249.282550221413, 10289.176865850714, 10319.277650564389, 10321.174306055498, 10165.390076595428, 10288.228382871315, 10298.588056477656, 10305.587776489007, 10323.432211228304, 7452.85094298111, 7860.764177074697, 8047.034355877215, 8069.507141178783, 8074.757757267292, 5650.604457687922, 5746.0, 5756.942430575808, 5855.23881895368, 5866.853328666817, 7810.9218907812465, 9217.308701847522, 9375.624975000923, 9445.905540944777, 9548.42709437436, 8443.788905277417, 9190.988991210828, 9580.610811432443, 9589.808203836024, 9710.417374957526, 6648.16897887764, 6864.073350097673, 7115.67979440916, 7122.715091396493, 7350.70597176107, 7921.801954950942, 8080.555569443502, 8144.0, 8146.0, 8201.95899020501, 7175.497715159889, 7249.311315425026, 7320.560766354036, 7395.519291246052, 7430.108386993596, 6186.381361863958, 7073.3987611053635, 7127.46544009191, 7135.286471352823, 7301.853962920605, 6789.558678685869, 6794.966025169842, 6876.415504682158, 6878.8624227516175, 6881.621510816954, 4777.641676874179, 4837.105135549935, 4859.829905953275, 4899.04468628614, 4943.431505376784, 4586.862394128181, 4866.537678920497, 4899.387576552931, 4958.603311734981, 4973.055119527278], "value": 4393.991210376993, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.59376326367556, 307.2349848876298, 313.68631368631367, 317.7981981441784, 318.4904153354631, 5353.5984801139875, 5409.0, 5430.75561599728, 5623.634463759853, 5661.348944871336, 4974.0, 5259.658787009307, 5259.86850328742, 5456.263404440399, 5464.672119672812, 4027.859024934128, 4184.3305071188615, 4202.768847713374, 4215.683823713219, 4215.915681686364, 3927.9018024549473, 4022.296098182821, 4071.6131967463043, 4190.937135942963, 4218.409422680827, 4136.917261654765, 4144.140543025318, 4155.0, 4156.376543518481, 4157.875263742093, 3458.170039190576, 4150.522689890656, 4151.5640857709805, 4197.538270790211, 4204.5375008749315, 3640.6541378568995, 3648.945265821015, 3652.064405864896, 3657.8902632921054, 3669.7064234861145, 4608.33179189019, 5157.252198431244, 5299.3905700844125, 5327.273181330085, 5329.6002799789785, 4402.911941761146, 4760.666753327268, 4772.73749943753, 4796.496367881357, 4818.061486975843, 5074.756684612294, 5128.307678463397, 5448.891022179534, 5795.391483894173, 5836.620619659709, 3580.3018411409785, 3912.4131380293184, 3945.467361906163, 3953.5848735882605, 3953.644172024503, 5651.519620832234, 6159.538034647375, 6188.195534580521, 6218.481514986438, 6239.438450539473, 3261.6249131349723, 3681.1533347330264, 3758.8684396046556, 3975.940360894589, 4011.338129208706, 3369.7809642373168, 3761.962380376161, 3955.980220098881, 4051.4530538376944, 4055.9391609125887, 3991.3613821788376, 4352.823588205897, 4745.311929770164, 4768.856934291976, 4777.1878780606685, 5174.826572839416, 5483.013057649661, 5911.261092363455, 5913.438223368773, 5991.7902873399225, 1152.2395219155317, 1241.7728818277537, 1254.6424269083268, 1260.9786073280673, 1305.484333688188, 791.0, 833.3708050422014, 883.7613844262013, 885.2652298592153, 897.438457084668, 5219.3736751589295, 5219.556337711261, 5229.738513074331, 5242.13504771716, 5244.659097158673, 4657.0, 4859.1496488114435, 5573.41479144692, 5669.546436285087, 5714.428557144252, 4339.913201736011, 4509.751963641966, 5127.179651255781, 5141.2288156776785, 5229.660072095302, 3004.0, 3054.2364408897774, 3055.1363668162403, 3061.943629447837, 3065.9233519161753, 5128.512791284822, 5275.366955965232, 5324.163163708515, 6007.339192688801, 6084.908726369108, 4901.681390709592, 5420.560747663489, 5805.390434004453, 5957.642541447527, 5970.0, 6013.879722405527, 6026.583752818085, 6052.214596693898, 6054.788082417094, 6085.391460853879, 5618.128652057626, 5655.398516682372, 5794.841811212983, 5798.913016304675, 5843.240378750906, 5170.7156106413, 5303.4431384705595, 5695.319880635306, 5699.406769919594, 5716.314042314949, 5107.259447380184, 5930.229070220844, 5961.314448838439, 6017.216313031064, 6052.546059045501, 3348.9665103348657, 3375.1730825947798, 3596.2447885944243, 3670.9904776186763, 3728.4034554471154, 5483.861294095385, 5506.779728810803, 5775.566832487664, 5784.392638772868, 5837.789215931136, 4755.476897541199, 4960.305557222064, 5426.945730542644, 5803.390643982321, 5839.0, 5055.292259083658, 5554.888902221852, 5800.970995145166, 5848.970755146197, 5871.266091738533, 5397.271368365377, 5675.744591493475, 5697.287839020122, 5704.4597958551785, 5706.629069110494, 5827.679477628772, 5846.41535846412, 5870.735816888165, 5876.236089308362, 5903.498202652822, 4976.213320540509, 5110.361204849394, 5581.525570326406, 5755.4460295720755, 5764.971175144097], "value": 4152.667524377904, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.8051457805592, 653.3923451190391, 685.5817951049862, 715.7745310227278, 727.9890801637977, 12160.452779624917, 13892.930535347321, 14691.191984440864, 15360.84639153608, 16052.11713355765, 14230.928845355784, 14322.570322890304, 14378.06542574732, 14398.712025759478, 14415.495457658984, 801.63926233195, 840.0423517190408, 840.6637345061963, 842.4818736477054, 843.6498852976008, 651.6937039591385, 672.740994717035, 808.7048227396987, 825.2613910550054, 829.2909562324216, 856.7986523167051, 874.8162885793959, 875.1379890807549, 876.0713643537838, 876.7369789063256, 849.8597731374316, 869.2046777198856, 869.5869462005528, 870.1907226279558, 871.3421366867974, 637.0221709675626, 645.2063961327547, 646.2116218213772, 647.5564238496597, 647.653505374627, 13695.452181912711, 13890.58328250154, 14259.714805703932, 14260.358283877147, 14294.85705142956, 10582.0, 12222.144449888512, 12273.202241854251, 12362.381880906009, 12449.128561000734, 10188.490575471196, 10221.233407494468, 10271.435071071162, 10360.378377297386, 10641.414722190277, 3269.0683155300626, 3280.114369120348, 3323.202431416467, 3353.6814002669707, 3373.207296285371, 88571.0, 92096.0, 92813.0, 93040.53479732625, 93549.79025943781, 9363.578638961262, 9409.529523523797, 9431.339806213637, 9458.810823783486, 9702.563384647709, 19283.0, 19487.415377538695, 19526.218951242074, 19835.900820495805, 19912.303069392503, 7104.396126329217, 7177.497575169688, 7186.856262874713, 7337.88993165103, 7346.449016323797, 3820.3887378019385, 3929.0, 3934.272159650474, 3940.408938659224, 3941.625545573166, 3256.7557433192605, 3338.2989572190113, 3346.0129261867805, 3359.462486002277, 3360.2103505676146, 579.3019411609001, 589.6285340235625, 590.9911351329733, 593.2169536212159, 603.7283222549783, 575.168880967007, 595.184597101973, 604.1511676095093, 613.4662843326334, 613.7913109542777, 8143.796405089802, 8545.957270213734, 8597.636970935764, 8760.737177884674, 8816.250618697355, 472.6431544184119, 597.1908064572528, 600.9939900600938, 606.8695230525458, 612.6630353305665, 3351.8994430167136, 4056.452378928866, 4137.2076569511455, 4180.770057646798, 4285.97857010713, 94358.52820735853, 98557.0, 105586.94413055925, 106159.89121957967, 110591.0, 80401.0, 81797.18202818015, 83477.0, 83563.0, 88001.55999220091, 89314.10685893058, 89773.55113224393, 90207.19585608252, 90573.0, 90800.5459972709, 8240.793980150544, 8273.958630206811, 8318.45930014547, 8344.541050242295, 8377.53923534187, 44039.89900252519, 52292.73853630707, 53364.1995370062, 55310.72344638251, 57572.424275756704, 54860.805567778436, 55209.06768263091, 55536.44463555474, 71093.64453177701, 80200.19799801927, 9363.625454981997, 11654.650360489199, 12000.0, 12110.455029523517, 12342.382880855741, 13191.80212296797, 13382.063255572017, 13469.191848489121, 13537.052406331855, 13921.58235252944, 11960.76078478443, 11974.760504790029, 12131.514739410324, 12436.19164754288, 12475.812862807246, 12253.528835198325, 12291.754164916472, 12673.619791406272, 12734.68163295925, 12987.28569928663, 11864.169508134348, 11884.762304753684, 11974.401279935793, 12429.192102513307, 12473.814987576166, 8511.787205319917, 10436.791264174826, 10442.425666588108, 10552.050315471337, 11025.0, 9515.381500202464, 10290.485475726333, 10511.894881051092, 10555.155587552823, 10653.147748180281], "value": 6933.124301351191, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 118.0, 117.0, 122.0, 145.0, 172.0, 168.0, 116.0, 117.0], "value": 130.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 101.0, 186.0, 153.0, 137.0, 115.0, 105.0, 178.0, 120.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 206.0, 246.0, 199.0, 197.0, 196.0, 236.0, 276.0, 233.0, 195.0], "value": 219.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 59.0, 84.0, 61.0, 52.0, 68.0, 82.0, 106.0, 57.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 106.0, 96.0, 100.0, 103.0, 119.0, 70.0, 70.0, 88.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 102.0, 101.0, 86.0, 78.0, 132.0, 138.0, 86.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 99.0, 100.0, 101.0, 100.0, 100.0, 100.0, 103.0, 100.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 100.0, 86.0, 88.0, 93.0, 89.0, 121.0, 98.0, 74.0, 120.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 57.0, 57.0, 57.0, 57.0, 64.0, 78.0, 85.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 74.0, 80.0, 74.0, 69.0, 72.0, 73.0, 72.0, 74.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 90.0, 90.0, 89.0, 89.0, 87.0, 89.0, 91.0, 92.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 158.0, 147.0, 149.0, 147.0, 145.0, 146.0, 175.0, 222.0, 178.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 175.0, 175.0, 177.0, 259.0, 241.0, 188.0, 176.0, 175.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 67.0, 67.0, 68.0, 69.0, 69.0, 69.0, 68.0, 69.0], "value": 68.4, "unit": "score"}]}]} 08:53:04 INFO - Return code: 0 08:53:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:53:04 ERROR - # TBPL SUCCESS # 08:53:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:04 INFO - 2016-11-14 08:53:04.166 screenresolution[1672:11335] starting screenresolution argv=screenresolution get 08:53:04 INFO - 2016-11-14 08:53:04.169 screenresolution[1672:11335] Display 0: 1600x1200x32@60 08:53:04 INFO - 2016-11-14 08:53:04.175 screenresolution[1673:11338] starting screenresolution argv=screenresolution list 08:53:04 INFO - Available Modes on Display 0 08:53:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:04 INFO - Intel Iris: 08:53:04 INFO - Chipset Model: Intel Iris 08:53:04 INFO - Type: GPU 08:53:04 INFO - Bus: Built-In 08:53:04 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:04 INFO - Vendor: Intel (0x8086) 08:53:04 INFO - Device ID: 0x0a2e 08:53:04 INFO - Revision ID: 0x0009 08:53:04 INFO - Displays: 08:53:04 INFO - Display: 08:53:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:04 INFO - Display Serial Number: 1600x1200 60 08:53:04 INFO - Main Display: Yes 08:53:04 INFO - Mirror: Off 08:53:04 INFO - Online: Yes 08:53:04 INFO - Rotation: Supported 08:53:04 INFO - Return code: 0 08:53:04 INFO - Running post-action listener: _resource_record_post_action 08:53:04 INFO - [mozharness: 2016-11-14 16:53:04.411013Z] Finished run-tests step (success) 08:53:04 INFO - Running post-run listener: _resource_record_post_run 08:53:04 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39692800; Write bytes: 702674944; Read time: 387; Write time: 2875 08:53:04 INFO - TinderboxPrint: CPU usage
54.7% 08:53:04 INFO - TinderboxPrint: I/O read bytes / time
39,692,800 / 387 08:53:04 INFO - TinderboxPrint: I/O write bytes / time
702,674,944 / 2,875 08:53:04 INFO - TinderboxPrint: CPU idle
2,193.0 (45.3%) 08:53:04 INFO - TinderboxPrint: CPU system
984.9 (20.3%) 08:53:04 INFO - TinderboxPrint: CPU user
1,667.8 (34.4%) 08:53:04 INFO - TinderboxPrint: Swap in / out
438,415,360 / 0 08:53:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398242304; Write bytes: 391521792; Read time: 29744; Write time: 689 08:53:04 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35473920; Write bytes: 300868096; Read time: 354; Write time: 2155 08:53:04 INFO - Running post-run listener: _upload_blobber_files 08:53:04 INFO - Blob upload gear active. 08:53:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:53:04 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 08:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:53:05 INFO - (blobuploader) - INFO - Open directory for files ... 08:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:07 INFO - (blobuploader) - INFO - Done attempting. 08:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:53:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:08 INFO - (blobuploader) - INFO - Done attempting. 08:53:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:53:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:53:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:10 INFO - (blobuploader) - INFO - Done attempting. 08:53:10 INFO - (blobuploader) - INFO - Iteration through files over. 08:53:10 INFO - Return code: 0 08:53:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:53:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:53:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02816fcddea5831cd888dea6c0713bd31a361f4423649d9b43aa587ea685198c899371fdfdb96c8258ca105692249177e39caf5e428661ecb6e727bbf4c685e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c12a1832ed4fb84c95600519fd4f360cca87e6fe85e5309b52b63ad006d2ddb3162653e309cc499308660fc5138929f8460bec355ea19794e505d42051001628", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15bf8b0cc937f2b115eb34c73223a1956d8f8f6b209f3947faa5fcd4ac5c9a69541cb9bc6a399b9890f3325f9ebc05e53fa63ac1470dcf6266eb1cb49f335c0a"} 08:53:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:53:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:53:10 INFO - Contents: 08:53:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02816fcddea5831cd888dea6c0713bd31a361f4423649d9b43aa587ea685198c899371fdfdb96c8258ca105692249177e39caf5e428661ecb6e727bbf4c685e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c12a1832ed4fb84c95600519fd4f360cca87e6fe85e5309b52b63ad006d2ddb3162653e309cc499308660fc5138929f8460bec355ea19794e505d42051001628", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15bf8b0cc937f2b115eb34c73223a1956d8f8f6b209f3947faa5fcd4ac5c9a69541cb9bc6a399b9890f3325f9ebc05e53fa63ac1470dcf6266eb1cb49f335c0a"} 08:53:10 INFO - Running post-run listener: copy_logs_to_upload_dir 08:53:10 INFO - Copying logs to upload dir... 08:53:10 INFO - mkdir: /builds/slave/test/build/upload/logs 08:53:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.983181 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 08:53:10.836091) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 08:53:10.842526) ========= 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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02816fcddea5831cd888dea6c0713bd31a361f4423649d9b43aa587ea685198c899371fdfdb96c8258ca105692249177e39caf5e428661ecb6e727bbf4c685e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c12a1832ed4fb84c95600519fd4f360cca87e6fe85e5309b52b63ad006d2ddb3162653e309cc499308660fc5138929f8460bec355ea19794e505d42051001628", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15bf8b0cc937f2b115eb34c73223a1956d8f8f6b209f3947faa5fcd4ac5c9a69541cb9bc6a399b9890f3325f9ebc05e53fa63ac1470dcf6266eb1cb49f335c0a"} build_url:https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/YWm6YO-bTG2AW7IyYVXm4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02816fcddea5831cd888dea6c0713bd31a361f4423649d9b43aa587ea685198c899371fdfdb96c8258ca105692249177e39caf5e428661ecb6e727bbf4c685e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c12a1832ed4fb84c95600519fd4f360cca87e6fe85e5309b52b63ad006d2ddb3162653e309cc499308660fc5138929f8460bec355ea19794e505d42051001628", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15bf8b0cc937f2b115eb34c73223a1956d8f8f6b209f3947faa5fcd4ac5c9a69541cb9bc6a399b9890f3325f9ebc05e53fa63ac1470dcf6266eb1cb49f335c0a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 08:53:10.876218) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 08:53:10.876569) ========= 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.0sTco5RXMJ/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.0TkX7RW4wL/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.005413 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 08:53:10.925002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 08:53:10.925338) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 08:53:10.925688) ========= ========= Total master_lag: 0.17 =========