builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0147 starttime: 1486528829.05 results: success (0) buildid: 20170207183857 builduid: c514cc895045425f890c69003adf65a2 revision: f3b0ab9fdef6be72279fbe5d32668628bcb62d9e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:29.051040) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:29.051500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:29.051783) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:29.155799) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 20:40:29.156179) ========= 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.xKPbJRqtB2/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.85JPBzXxu4/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 --2017-02-07 20:40:29-- 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% 15.8M=0.001s 2017-02-07 20:40:29 (15.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134757 ========= master_lag: 1.43 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 20:40:30.723592) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 20:40:30.723867) ========= 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.xKPbJRqtB2/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.85JPBzXxu4/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.027757 ========= master_lag: 1.91 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 20:40:32.660714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-07 20:40:32.661114) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f3b0ab9fdef6be72279fbe5d32668628bcb62d9e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f3b0ab9fdef6be72279fbe5d32668628bcb62d9e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xKPbJRqtB2/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.85JPBzXxu4/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 2017-02-07 20:40:32,767 truncating revision to first 12 chars 2017-02-07 20:40:32,767 Setting DEBUG logging. 2017-02-07 20:40:32,767 attempt 1/10 2017-02-07 20:40:32,767 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f3b0ab9fdef6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 20:40:35,113 unpacking tar archive at: mozilla-beta-f3b0ab9fdef6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.593639 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-07 20:40:35.283078) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:35.286408) ========= script_repo_revision: f3b0ab9fdef6be72279fbe5d32668628bcb62d9e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:35.286789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:35.287051) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 20:40:35.306943) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-07 20:40:35.307343) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xKPbJRqtB2/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.85JPBzXxu4/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:40:35 INFO - MultiFileLogger online at 20170207 20:40:35 in /builds/slave/test 20:40:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 20:40:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:40:35 INFO - {'append_to_log': False, 20:40:35 INFO - 'base_work_dir': '/builds/slave/test', 20:40:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:40:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:40:35 INFO - 'branch': 'Mozilla-Beta', 20:40:35 INFO - 'buildbot_json_path': 'buildprops.json', 20:40:35 INFO - 'config_files': ('talos/mac_config.py',), 20:40:35 INFO - 'default_actions': ('clobber', 20:40:35 INFO - 'read-buildbot-config', 20:40:35 INFO - 'download-and-extract', 20:40:35 INFO - 'populate-webroot', 20:40:35 INFO - 'create-virtualenv', 20:40:35 INFO - 'install', 20:40:35 INFO - 'run-tests'), 20:40:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:40:35 INFO - 'download_minidump_stackwalk': True, 20:40:35 INFO - 'download_symbols': 'ondemand', 20:40:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:40:35 INFO - 'tooltool.py': '/tools/tooltool.py', 20:40:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:40:35 INFO - '/tools/misc-python/virtualenv.py')}, 20:40:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:40:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:40:35 INFO - 'installer_path': 'installer.exe', 20:40:35 INFO - 'log_level': 'info', 20:40:35 INFO - 'log_name': 'talos', 20:40:35 INFO - 'log_to_console': True, 20:40:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:40:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:40:35 INFO - 'opt_config_files': (), 20:40:35 INFO - 'pip_index': False, 20:40:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:40:35 INFO - 'cmd': ('bash', 20:40:35 INFO - '-c', 20:40:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:40:35 INFO - 'enabled': True, 20:40:35 INFO - 'halt_on_failure': False, 20:40:35 INFO - 'name': 'check_screen_resolution'},), 20:40:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:40:35 INFO - 'cmd': ('bash', 20:40:35 INFO - '-c', 20:40:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:40:35 INFO - 'enabled': True, 20:40:35 INFO - 'halt_on_failure': False, 20:40:35 INFO - 'name': 'check_screen_resolution'},), 20:40:35 INFO - 'run_cmd_checks_enabled': True, 20:40:35 INFO - 'sps_profile': False, 20:40:35 INFO - 'sps_profile_interval': 0, 20:40:35 INFO - 'suite': 'dromaeojs-e10s', 20:40:35 INFO - 'system_bits': '32', 20:40:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:40:35 INFO - 'title': 't-yosemite-r7-0147', 20:40:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:40:35 INFO - 'use_talos_json': True, 20:40:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:40:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:40:35 INFO - 'work_dir': 'build'} 20:40:35 INFO - [mozharness: 2017-02-08 04:40:35.518588Z] Running clobber step. 20:40:35 INFO - Running pre-action listener: _resource_record_pre_action 20:40:35 INFO - Running main action method: clobber 20:40:35 INFO - rmtree: /builds/slave/test/build 20:40:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:40:38 INFO - Running post-action listener: _resource_record_post_action 20:40:38 INFO - [mozharness: 2017-02-08 04:40:38.162698Z] Finished clobber step (success) 20:40:38 INFO - [mozharness: 2017-02-08 04:40:38.162818Z] Running read-buildbot-config step. 20:40:38 INFO - Running pre-action listener: _resource_record_pre_action 20:40:38 INFO - Running main action method: read_buildbot_config 20:40:38 INFO - Using buildbot properties: 20:40:38 INFO - { 20:40:38 INFO - "project": "", 20:40:38 INFO - "product": "firefox", 20:40:38 INFO - "script_repo_revision": "production", 20:40:38 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 20:40:38 INFO - "repository": "", 20:40:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 20:40:38 INFO - "buildid": "20170207183857", 20:40:38 INFO - "pgo_build": "False", 20:40:38 INFO - "basedir": "/builds/slave/test", 20:40:38 INFO - "buildnumber": 10, 20:40:38 INFO - "slavename": "t-yosemite-r7-0147", 20:40:38 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:40:38 INFO - "platform": "yosemite_r7", 20:40:38 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 20:40:38 INFO - "branch": "mozilla-beta", 20:40:38 INFO - "repo_path": "releases/mozilla-beta", 20:40:38 INFO - "revision": "f3b0ab9fdef6be72279fbe5d32668628bcb62d9e", 20:40:38 INFO - "stage_platform": "macosx64", 20:40:38 INFO - "builduid": "c514cc895045425f890c69003adf65a2", 20:40:38 INFO - "slavebuilddir": "test" 20:40:38 INFO - } 20:40:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 20:40:38 INFO - Running post-action listener: _resource_record_post_action 20:40:38 INFO - [mozharness: 2017-02-08 04:40:38.166135Z] Finished read-buildbot-config step (success) 20:40:38 INFO - [mozharness: 2017-02-08 04:40:38.166223Z] Running download-and-extract step. 20:40:38 INFO - Running pre-action listener: _resource_record_pre_action 20:40:38 INFO - Running main action method: download_and_extract 20:40:38 INFO - mkdir: /builds/slave/test/build/tests 20:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:40:38 INFO - trying https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 20:40:38 INFO - Downloading https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 20:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 20:40:39 INFO - Downloaded 1165 bytes. 20:40:39 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 20:40:39 INFO - Using the following test package requirements: 20:40:39 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 20:40:39 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 20:40:39 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 20:40:39 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:40:39 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 20:40:39 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 20:40:39 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 20:40:39 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 20:40:39 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 20:40:39 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 20:40:39 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 20:40:39 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 20:40:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 20:40:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 20:40:39 INFO - Fetch https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 20:40:40 INFO - Http code: 200 20:40:40 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 20:40:40 INFO - x-amz-cf-id: 7kU7NZ-loAwZC3zfWyboLHeSMEw7AI4tDImEkQ76zhGjVObAgHJysw== 20:40:40 INFO - x-amz-version-id: QwWSyLS0zLMgWNL7NsQPu.ms8iRQLXZ. 20:40:40 INFO - Expected file size: 16092975 20:40:40 INFO - Obtained file size: 16092975 20:40:46 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 20:40:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 20:40:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 20:40:46 INFO - Fetch https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 20:40:47 INFO - Http code: 200 20:40:47 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 20:40:47 INFO - x-amz-cf-id: CUhWLoQswjITLey62SFZshZFi0ejHCVXSA_OG-N_-eL6WbWLJ9ExUg== 20:40:47 INFO - x-amz-version-id: QwWSyLS0zLMgWNL7NsQPu.ms8iRQLXZ. 20:40:47 INFO - Expected file size: 16092975 20:40:47 INFO - Obtained file size: 16092975 20:40:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 20:40:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 20:40:54 INFO - Fetch https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 20:40:54 INFO - Http code: 200 20:40:54 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 20:40:54 INFO - x-amz-cf-id: aNb4u7vuKJgHvAg3kMAMtCnZPt-yTow1Y8-mqSNoZMmCCbR_vGQB_A== 20:40:54 INFO - x-amz-version-id: .SUAYdtKYwa7LHFfqpJcTZu3E2Dfch_N 20:40:55 INFO - Expected file size: 14403005 20:40:55 INFO - Obtained file size: 14403005 20:40:55 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')]} 20:40:55 INFO - trying https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 20:40:55 INFO - Downloading https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 20:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 20:40:57 INFO - Downloaded 82141863 bytes. 20:40:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 20:40:57 INFO - mkdir: /builds/slave/test/properties 20:40:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:40:57 INFO - Writing to file /builds/slave/test/properties/build_url 20:40:57 INFO - Contents: 20:40:57 INFO - build_url:https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 20:40:58 INFO - Running post-action listener: _resource_record_post_action 20:40:58 INFO - Running post-action listener: set_extra_try_arguments 20:40:58 INFO - [mozharness: 2017-02-08 04:40:58.239972Z] Finished download-and-extract step (success) 20:40:58 INFO - [mozharness: 2017-02-08 04:40:58.240102Z] Running populate-webroot step. 20:40:58 INFO - Running pre-action listener: _resource_record_pre_action 20:40:58 INFO - Running main action method: populate_webroot 20:40:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:40:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:40:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:40:58 INFO - '--tppagecycles', 20:40:58 INFO - '7'], 20:40:58 INFO - 'tests': ['tsvgm']}}, 20:40:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:40:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:40:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:40:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:40:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:40:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:40:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['damp', 'tps']}, 20:40:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'tests': ['damp', 'tps']}, 20:40:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['dromaeo_dom']}, 20:40:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:40:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['basic_compositor_video']}, 20:40:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:40:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['a11yr', 20:40:58 INFO - 'ts_paint', 20:40:58 INFO - 'tpaint', 20:40:58 INFO - 'sessionrestore', 20:40:58 INFO - 'sessionrestore_no_auto_restore', 20:40:58 INFO - 'tabpaint']}, 20:40:58 INFO - 'other-e10s': {'tests': ['a11yr', 20:40:58 INFO - 'ts_paint', 20:40:58 INFO - 'tpaint', 20:40:58 INFO - 'sessionrestore', 20:40:58 INFO - 'sessionrestore_no_auto_restore', 20:40:58 INFO - 'tabpaint']}, 20:40:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:40:58 INFO - 'ts_paint', 20:40:58 INFO - 'tpaint', 20:40:58 INFO - 'sessionrestore', 20:40:58 INFO - 'sessionrestore_no_auto_restore', 20:40:58 INFO - 'tabpaint']}, 20:40:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:40:58 INFO - 'ts_paint', 20:40:58 INFO - 'tpaint', 20:40:58 INFO - 'sessionrestore', 20:40:58 INFO - 'sessionrestore_no_auto_restore', 20:40:58 INFO - 'tabpaint']}, 20:40:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['a11yr', 20:40:58 INFO - 'ts_paint', 20:40:58 INFO - 'tpaint', 20:40:58 INFO - 'sessionrestore', 20:40:58 INFO - 'sessionrestore_no_auto_restore', 20:40:58 INFO - 'tabpaint']}, 20:40:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['a11yr', 20:40:58 INFO - 'ts_paint', 20:40:58 INFO - 'tpaint', 20:40:58 INFO - 'sessionrestore', 20:40:58 INFO - 'sessionrestore_no_auto_restore', 20:40:58 INFO - 'tabpaint']}, 20:40:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['tsvgx', 20:40:58 INFO - 'tsvgr_opacity', 20:40:58 INFO - 'tart', 20:40:58 INFO - 'tscrollx', 20:40:58 INFO - 'cart']}, 20:40:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:40:58 INFO - 'tsvgr_opacity', 20:40:58 INFO - 'tart', 20:40:58 INFO - 'tscrollx', 20:40:58 INFO - 'cart']}, 20:40:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'talos_options': ['--disable-e10s'], 20:40:58 INFO - 'tests': ['tp5o']}, 20:40:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'tests': ['tp5o']}, 20:40:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'talos_options': ['--disable-e10s', 20:40:58 INFO - '--xperf_path', 20:40:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:40:58 INFO - 'tests': ['tp5n']}, 20:40:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:40:58 INFO - 'talos_options': ['--xperf_path', 20:40:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:40:58 INFO - 'tests': ['tp5n']}}, 20:40:58 INFO - 'talos.zip': {'path': '', 20:40:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:40:58 INFO - Running post-action listener: _resource_record_post_action 20:40:58 INFO - [mozharness: 2017-02-08 04:40:58.258811Z] Finished populate-webroot step (success) 20:40:58 INFO - [mozharness: 2017-02-08 04:40:58.258943Z] Running create-virtualenv step. 20:40:58 INFO - Running pre-action listener: _resource_record_pre_action 20:40:58 INFO - Running main action method: create_virtualenv 20:40:58 INFO - Creating virtualenv /builds/slave/test/build/venv 20:40:58 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 20:40:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:40:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:40:58 INFO - Using real prefix '/tools/python27' 20:40:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:40:59 INFO - Installing distribute.............................................................................................................................................................................................done. 20:41:01 INFO - Installing pip.................done. 20:41:01 INFO - Return code: 0 20:41:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:41:02 INFO - Reading from file tmpfile_stdout 20:41:02 INFO - Output received: 20:41:02 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) 20:41:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:02 INFO - 'HOME': '/Users/cltbld', 20:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:02 INFO - 'LOGNAME': 'cltbld', 20:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:02 INFO - 'MOZ_NO_REMOTE': '1', 20:41:02 INFO - 'NO_EM_RESTART': '1', 20:41:02 INFO - 'PAGER': '/bin/cat', 20:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:02 INFO - 'PWD': '/builds/slave/test', 20:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:02 INFO - 'SHELL': '/bin/bash', 20:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:02 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:02 INFO - 'USER': 'cltbld', 20:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:02 INFO - 'XPC_FLAGS': '0x0', 20:41:02 INFO - 'XPC_SERVICE_NAME': '0', 20:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:02 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 20:41:02 INFO - Cleaning up... 20:41:02 INFO - Return code: 0 20:41:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:02 INFO - 'HOME': '/Users/cltbld', 20:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:02 INFO - 'LOGNAME': 'cltbld', 20:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:02 INFO - 'MOZ_NO_REMOTE': '1', 20:41:02 INFO - 'NO_EM_RESTART': '1', 20:41:02 INFO - 'PAGER': '/bin/cat', 20:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:02 INFO - 'PWD': '/builds/slave/test', 20:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:02 INFO - 'SHELL': '/bin/bash', 20:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:02 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:02 INFO - 'USER': 'cltbld', 20:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:02 INFO - 'XPC_FLAGS': '0x0', 20:41:02 INFO - 'XPC_SERVICE_NAME': '0', 20:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:02 INFO - Downloading/unpacking psutil>=3.1.1 20:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:41:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:41:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:41:06 INFO - Installing collected packages: psutil 20:41:06 INFO - Running setup.py install for psutil 20:41:06 INFO - building 'psutil._psutil_osx' extension 20:41:06 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 20:41:06 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 20:41:06 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 20:41:07 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 20:41:07 INFO - building 'psutil._psutil_posix' extension 20:41:07 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 20:41:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:41:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:41:07 INFO - ^ 20:41:07 INFO - 1 warning generated. 20:41:07 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 20:41:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:41:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:41:07 INFO - Successfully installed psutil 20:41:07 INFO - Cleaning up... 20:41:07 INFO - Return code: 0 20:41:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:41:07 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')]} 20:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:07 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')]} 20:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:07 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:07 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 20:41:07 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 20:41:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:07 INFO - 'HOME': '/Users/cltbld', 20:41:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:07 INFO - 'LOGNAME': 'cltbld', 20:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:07 INFO - 'MOZ_NO_REMOTE': '1', 20:41:07 INFO - 'NO_EM_RESTART': '1', 20:41:07 INFO - 'PAGER': '/bin/cat', 20:41:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:07 INFO - 'PWD': '/builds/slave/test', 20:41:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:07 INFO - 'SHELL': '/bin/bash', 20:41:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:07 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:07 INFO - 'USER': 'cltbld', 20:41:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:07 INFO - 'XPC_FLAGS': '0x0', 20:41:07 INFO - 'XPC_SERVICE_NAME': '0', 20:41:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:41:07 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 20:41:07 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 20:41:07 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 20:41:07 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 20:41:07 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 20:41:07 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 20:41:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:41:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:41:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:41:10 INFO - Installing collected packages: mozsystemmonitor 20:41:10 INFO - Running setup.py install for mozsystemmonitor 20:41:10 INFO - Successfully installed mozsystemmonitor 20:41:10 INFO - Cleaning up... 20:41:10 INFO - Return code: 0 20:41:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:41:10 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')]} 20:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:10 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')]} 20:41:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:10 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:10 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 20:41:10 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 20:41:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:10 INFO - 'HOME': '/Users/cltbld', 20:41:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:10 INFO - 'LOGNAME': 'cltbld', 20:41:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:10 INFO - 'MOZ_NO_REMOTE': '1', 20:41:10 INFO - 'NO_EM_RESTART': '1', 20:41:10 INFO - 'PAGER': '/bin/cat', 20:41:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:10 INFO - 'PWD': '/builds/slave/test', 20:41:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:10 INFO - 'SHELL': '/bin/bash', 20:41:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:10 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:10 INFO - 'USER': 'cltbld', 20:41:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:10 INFO - 'XPC_FLAGS': '0x0', 20:41:10 INFO - 'XPC_SERVICE_NAME': '0', 20:41:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:10 INFO - Downloading/unpacking jsonschema==2.5.1 20:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:41:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:13 INFO - Downloading functools32-3.2.3-2.tar.gz 20:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:41:13 INFO - warning: no files found matching '*.txt' 20:41:13 INFO - no previously-included directories found matching 'build' 20:41:13 INFO - no previously-included directories found matching 'dist' 20:41:13 INFO - no previously-included directories found matching '.git*' 20:41:13 INFO - Installing collected packages: jsonschema, functools32 20:41:13 INFO - Running setup.py install for functools32 20:41:14 INFO - warning: no files found matching '*.txt' 20:41:14 INFO - no previously-included directories found matching 'build' 20:41:14 INFO - no previously-included directories found matching 'dist' 20:41:14 INFO - no previously-included directories found matching '.git*' 20:41:14 INFO - Successfully installed jsonschema functools32 20:41:14 INFO - Cleaning up... 20:41:14 INFO - Return code: 0 20:41:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:41:14 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')]} 20:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:14 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')]} 20:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:14 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:14 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 20:41:14 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 20:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:14 INFO - 'HOME': '/Users/cltbld', 20:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:14 INFO - 'LOGNAME': 'cltbld', 20:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:14 INFO - 'MOZ_NO_REMOTE': '1', 20:41:14 INFO - 'NO_EM_RESTART': '1', 20:41:14 INFO - 'PAGER': '/bin/cat', 20:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:14 INFO - 'PWD': '/builds/slave/test', 20:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:14 INFO - 'SHELL': '/bin/bash', 20:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:14 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:14 INFO - 'USER': 'cltbld', 20:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:14 INFO - 'XPC_FLAGS': '0x0', 20:41:14 INFO - 'XPC_SERVICE_NAME': '0', 20:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:41:14 INFO - Cleaning up... 20:41:14 INFO - Return code: 0 20:41:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:41:14 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')]} 20:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:14 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')]} 20:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:14 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:14 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 20:41:14 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 20:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:14 INFO - 'HOME': '/Users/cltbld', 20:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:14 INFO - 'LOGNAME': 'cltbld', 20:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:14 INFO - 'MOZ_NO_REMOTE': '1', 20:41:14 INFO - 'NO_EM_RESTART': '1', 20:41:14 INFO - 'PAGER': '/bin/cat', 20:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:14 INFO - 'PWD': '/builds/slave/test', 20:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:14 INFO - 'SHELL': '/bin/bash', 20:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:14 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:14 INFO - 'USER': 'cltbld', 20:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:14 INFO - 'XPC_FLAGS': '0x0', 20:41:14 INFO - 'XPC_SERVICE_NAME': '0', 20:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:14 INFO - Downloading/unpacking blobuploader==1.2.4 20:41:14 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 20:41:14 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 20:41:14 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 20:41:14 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 20:41:14 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 20:41:14 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 20:41:17 INFO - Downloading blobuploader-1.2.4.tar.gz 20:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:41:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:41:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:41:18 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 20:41:18 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 20:41:18 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 20:41:18 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 20:41:18 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 20:41:18 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 20:41:18 INFO - Downloading docopt-0.6.1.tar.gz 20:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:41:18 INFO - Installing collected packages: blobuploader, requests, docopt 20:41:18 INFO - Running setup.py install for blobuploader 20:41:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:41:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:41:18 INFO - Running setup.py install for requests 20:41:19 INFO - Running setup.py install for docopt 20:41:19 INFO - Successfully installed blobuploader requests docopt 20:41:19 INFO - Cleaning up... 20:41:19 INFO - Return code: 0 20:41:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:41:19 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')]} 20:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:19 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')]} 20:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:19 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:19 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 20:41:19 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 20:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:19 INFO - 'HOME': '/Users/cltbld', 20:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:19 INFO - 'LOGNAME': 'cltbld', 20:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:19 INFO - 'MOZ_NO_REMOTE': '1', 20:41:19 INFO - 'NO_EM_RESTART': '1', 20:41:19 INFO - 'PAGER': '/bin/cat', 20:41:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:19 INFO - 'PWD': '/builds/slave/test', 20:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:19 INFO - 'SHELL': '/bin/bash', 20:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:19 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:19 INFO - 'USER': 'cltbld', 20:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:19 INFO - 'XPC_FLAGS': '0x0', 20:41:19 INFO - 'XPC_SERVICE_NAME': '0', 20:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:41:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-8mKOLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:41:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-FMYykO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2J36ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-u2DSsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hNAWKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-0268HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-GArXLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hrsKmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-S95Y5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3zzyYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-alEAoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:41:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-opcfH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:41:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-butYSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:41:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-pcHgPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:41:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fjNHSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:41:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-y_N7P2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:41:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-4oF7NV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:41:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:41:21 INFO - Running setup.py install for manifestparser 20:41:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:41:21 INFO - Running setup.py install for mozcrash 20:41:21 INFO - Running setup.py install for mozdebug 20:41:21 INFO - Running setup.py install for mozdevice 20:41:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Running setup.py install for mozfile 20:41:22 INFO - Running setup.py install for mozhttpd 20:41:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Running setup.py install for mozinfo 20:41:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Running setup.py install for mozInstall 20:41:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:41:22 INFO - Running setup.py install for mozleak 20:41:22 INFO - Running setup.py install for mozlog 20:41:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:41:23 INFO - Running setup.py install for moznetwork 20:41:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:41:23 INFO - Running setup.py install for mozprocess 20:41:23 INFO - Running setup.py install for mozprofile 20:41:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:41:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:41:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:41:23 INFO - Running setup.py install for mozrunner 20:41:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:41:23 INFO - Running setup.py install for mozscreenshot 20:41:23 INFO - Running setup.py install for moztest 20:41:23 INFO - Running setup.py install for mozversion 20:41:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:41:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:41:24 INFO - Cleaning up... 20:41:24 INFO - Return code: 0 20:41:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:41: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')]} 20:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41: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')]} 20:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:24 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:24 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 20:41:24 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 20:41:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:24 INFO - 'HOME': '/Users/cltbld', 20:41:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:24 INFO - 'LOGNAME': 'cltbld', 20:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:24 INFO - 'MOZ_NO_REMOTE': '1', 20:41:24 INFO - 'NO_EM_RESTART': '1', 20:41:24 INFO - 'PAGER': '/bin/cat', 20:41:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:24 INFO - 'PWD': '/builds/slave/test', 20:41:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:24 INFO - 'SHELL': '/bin/bash', 20:41:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:24 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:24 INFO - 'USER': 'cltbld', 20:41:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:24 INFO - 'XPC_FLAGS': '0x0', 20:41:24 INFO - 'XPC_SERVICE_NAME': '0', 20:41:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6Ikl54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-q764Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fvvTFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-HMxzH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-TaInYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-osdSPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-lmAUjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:41:25 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)) 20:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:41:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-CpIAyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-K8wU0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Ji9aiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-m9LJDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-TzX1UM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-yfRVRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-wkT_ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-IRhD2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:41:26 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-x6ePsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 20:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:41:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-dq0zfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:41:26 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)) 20:41:26 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)) 20:41:26 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)) 20:41:26 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)) 20:41:26 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)) 20:41:26 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)) 20:41:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:41:26 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 20:41:26 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 20:41:26 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 20:41:26 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 20:41:26 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 20:41:26 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 20:41:29 INFO - Downloading blessings-1.6.tar.gz 20:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:41:29 INFO - Installing collected packages: blessings 20:41:29 INFO - Running setup.py install for blessings 20:41:30 INFO - Successfully installed blessings 20:41:30 INFO - Cleaning up... 20:41:30 INFO - Return code: 0 20:41:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:41:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:41:30 INFO - Reading from file tmpfile_stdout 20:41:30 INFO - Current package versions: 20:41:30 INFO - blessings == 1.6 20:41:30 INFO - blobuploader == 1.2.4 20:41:30 INFO - docopt == 0.6.1 20:41:30 INFO - functools32 == 3.2.3-2 20:41:30 INFO - jsonschema == 2.5.1 20:41:30 INFO - manifestparser == 1.1 20:41:30 INFO - mozInstall == 1.12 20:41:30 INFO - mozcrash == 1.0 20:41:30 INFO - mozdebug == 0.1 20:41:30 INFO - mozdevice == 0.48 20:41:30 INFO - mozfile == 1.2 20:41:30 INFO - mozhttpd == 0.7 20:41:30 INFO - mozinfo == 0.9 20:41:30 INFO - mozleak == 0.1 20:41:30 INFO - mozlog == 3.4 20:41:30 INFO - moznetwork == 0.27 20:41:30 INFO - mozprocess == 0.23 20:41:30 INFO - mozprofile == 0.28 20:41:30 INFO - mozrunner == 6.13 20:41:30 INFO - mozscreenshot == 0.1 20:41:30 INFO - mozsystemmonitor == 0.3 20:41:30 INFO - moztest == 0.8 20:41:30 INFO - mozversion == 1.4 20:41:30 INFO - psutil == 3.1.1 20:41:30 INFO - requests == 1.2.3 20:41:30 INFO - wsgiref == 0.1.2 20:41:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:30 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:30 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 20:41:30 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 20:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:30 INFO - 'HOME': '/Users/cltbld', 20:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:30 INFO - 'LOGNAME': 'cltbld', 20:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:30 INFO - 'MOZ_NO_REMOTE': '1', 20:41:30 INFO - 'NO_EM_RESTART': '1', 20:41:30 INFO - 'PAGER': '/bin/cat', 20:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:30 INFO - 'PWD': '/builds/slave/test', 20:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:30 INFO - 'SHELL': '/bin/bash', 20:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:30 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:30 INFO - 'USER': 'cltbld', 20:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:30 INFO - 'XPC_FLAGS': '0x0', 20:41:30 INFO - 'XPC_SERVICE_NAME': '0', 20:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 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)) 20:41:30 INFO - Cleaning up... 20:41:30 INFO - Return code: 0 20:41:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:30 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 0x10526b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcccb48de00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, '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.xKPbJRqtB2/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.85JPBzXxu4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 20:41:30 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 20:41:30 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 20:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:30 INFO - 'HOME': '/Users/cltbld', 20:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:30 INFO - 'LOGNAME': 'cltbld', 20:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:30 INFO - 'MOZ_NO_REMOTE': '1', 20:41:30 INFO - 'NO_EM_RESTART': '1', 20:41:30 INFO - 'PAGER': '/bin/cat', 20:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:30 INFO - 'PWD': '/builds/slave/test', 20:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:30 INFO - 'SHELL': '/bin/bash', 20:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:30 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:30 INFO - 'USER': 'cltbld', 20:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:30 INFO - 'XPC_FLAGS': '0x0', 20:41:30 INFO - 'XPC_SERVICE_NAME': '0', 20:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:41:31 INFO - Cleaning up... 20:41:31 INFO - Return code: 0 20:41:31 INFO - Running post-action listener: _resource_record_post_action 20:41:31 INFO - Running post-action listener: _start_resource_monitoring 20:41:31 INFO - Starting resource monitoring. 20:41:31 INFO - [mozharness: 2017-02-08 04:41:31.076533Z] Finished create-virtualenv step (success) 20:41:31 INFO - [mozharness: 2017-02-08 04:41:31.077539Z] Running install step. 20:41:31 INFO - Running pre-action listener: _resource_record_pre_action 20:41:31 INFO - Running main action method: install 20:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:41:31 INFO - Reading from file tmpfile_stdout 20:41:31 INFO - Detecting whether we're running mozinstall >=1.0... 20:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:41:31 INFO - Reading from file tmpfile_stdout 20:41:31 INFO - Output received: 20:41:31 INFO - Usage: mozinstall [options] installer 20:41:31 INFO - Options: 20:41:31 INFO - -h, --help show this help message and exit 20:41:31 INFO - -d DEST, --destination=DEST 20:41:31 INFO - Directory to install application into. [default: 20:41:31 INFO - "/builds/slave/test"] 20:41:31 INFO - --app=APP Application being installed. [default: firefox] 20:41:31 INFO - mkdir: /builds/slave/test/build/application 20:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 20:41:52 INFO - Reading from file tmpfile_stdout 20:41:52 INFO - Output received: 20:41:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 20:41:52 INFO - Running post-action listener: _resource_record_post_action 20:41:52 INFO - [mozharness: 2017-02-08 04:41:52.563873Z] Finished install step (success) 20:41:52 INFO - [mozharness: 2017-02-08 04:41:52.564040Z] Running run-tests step. 20:41:52 INFO - Running pre-action listener: _resource_record_pre_action 20:41:52 INFO - Running main action method: run_tests 20:41:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:41:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:41:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:41:52 INFO - 2017-02-07 20:41:52.577 screenresolution[1625:7305] starting screenresolution argv=screenresolution get 20:41:52 INFO - 2017-02-07 20:41:52.580 screenresolution[1625:7305] Display 0: 1600x1200x32@60 20:41:52 INFO - 2017-02-07 20:41:52.586 screenresolution[1626:7307] starting screenresolution argv=screenresolution list 20:41:52 INFO - Available Modes on Display 0 20:41:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:41:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:41:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:41:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:41:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:41:52 INFO - Intel Iris: 20:41:52 INFO - Chipset Model: Intel Iris 20:41:52 INFO - Type: GPU 20:41:52 INFO - Bus: Built-In 20:41:52 INFO - VRAM (Dynamic, Max): 1536 MB 20:41:52 INFO - Vendor: Intel (0x8086) 20:41:52 INFO - Device ID: 0x0a2e 20:41:52 INFO - Revision ID: 0x0009 20:41:52 INFO - Displays: 20:41:52 INFO - Display: 20:41:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:41:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:41:52 INFO - Display Serial Number: 1600x1200 60 20:41:52 INFO - Main Display: Yes 20:41:52 INFO - Mirror: Off 20:41:52 INFO - Online: Yes 20:41:52 INFO - Rotation: Supported 20:41:52 INFO - Return code: 0 20:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:41:52 INFO - Python 2.7.3 20:41:52 INFO - Return code: 0 20:41:52 INFO - grabbing minidump binary from tooltool 20:41:52 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')]} 20:41:52 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 0x10507fd30>, '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 0x104ee5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105077b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:41:52 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 20:41:52 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 20:41:52 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 20:41:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:41:52 INFO - Return code: 0 20:41:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:41:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:41:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:41:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:41:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 20:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 20:41:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKPbJRqtB2/Render', 20:41:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:52 INFO - 'HOME': '/Users/cltbld', 20:41:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:52 INFO - 'LOGNAME': 'cltbld', 20:41:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:41:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:52 INFO - 'MOZ_NO_REMOTE': '1', 20:41:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:41:52 INFO - 'NO_EM_RESTART': '1', 20:41:52 INFO - 'PAGER': '/bin/cat', 20:41:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:52 INFO - 'PWD': '/builds/slave/test', 20:41:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:41:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:52 INFO - 'SHELL': '/bin/bash', 20:41:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85JPBzXxu4/Listeners', 20:41:52 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 20:41:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:52 INFO - 'USER': 'cltbld', 20:41:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:52 INFO - 'XPC_FLAGS': '0x0', 20:41:52 INFO - 'XPC_SERVICE_NAME': '0', 20:41:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 20:41:53 INFO - mozversion application_buildid: 20170207183857 20:41:53 INFO - mozversion application_changeset: f3b0ab9fdef6be72279fbe5d32668628bcb62d9e 20:41:53 INFO - mozversion application_display_name: Firefox 20:41:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:41:53 INFO - mozversion application_name: Firefox 20:41:53 INFO - mozversion application_remotingname: firefox 20:41:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:41:53 INFO - mozversion application_vendor: Mozilla 20:41:53 INFO - mozversion application_version: 52.0 20:41:53 INFO - mozversion platform_buildid: 20170207183857 20:41:53 INFO - mozversion platform_changeset: f3b0ab9fdef6be72279fbe5d32668628bcb62d9e 20:41:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:41:53 INFO - mozversion platform_version: 52.0 20:41:53 INFO - using testdate: 1486528913 20:41:53 INFO - actual date: 1486528913 20:41:53 INFO - starting webserver on 'localhost:49246' 20:41:53 INFO - SUITE-START | Running 2 tests 20:41:53 INFO - TEST-START | dromaeo_css 20:41:53 INFO - operating with platform_type : mac_ 20:41:53 INFO - Initialising browser for dromaeo_css test... 20:41:53 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpZvr5N0/profile http://localhost:49246/getInfo.html) 20:41:56 INFO - PROCESS | 1636 | 2017-02-07 20:41:56.122 plugin-container[1639:7491] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x914f, name = 'com.apple.tsm.portname' 20:41:56 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:56 INFO - PROCESS | 1636 | 2017-02-07 20:41:56.122 plugin-container[1639:7491] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b37, name = 'com.apple.CFPasteboardClient' 20:41:56 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:56 INFO - PROCESS | 1636 | 2017-02-07 20:41:56.123 plugin-container[1639:7491] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:41:56 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 20:41:56 INFO - PROCESS | 1636 | colorDepth:24 20:41:56 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 20:41:56 INFO - PROCESS | 1636 | __metrics 20:41:56 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 20:41:57 INFO - TEST-INFO | 1636: exit 0 20:41:57 INFO - Browser initialized. 20:41:57 INFO - Running cycle 1/1 for dromaeo_css test... 20:41:57 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpZvr5N0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:59 INFO - PROCESS | 1640 | 2017-02-07 20:41:59.552 plugin-container[1641:7619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 20:41:59 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:59 INFO - PROCESS | 1640 | 2017-02-07 20:41:59.552 plugin-container[1641:7619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 20:41:59 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:59 INFO - PROCESS | 1640 | 2017-02-07 20:41:59.552 plugin-container[1641:7619] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:42:09 INFO - PROCESS | 1640 | RSS: Main: 176840704 20:42:09 INFO - PROCESS | 1640 | 20:45:11 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 20:45:13 INFO - PROCESS | 1640 | RSS: Main: 182628352 20:45:13 INFO - PROCESS | 1640 | 20:48:15 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 20:48:16 INFO - PROCESS | 1640 | RSS: Main: 196317184 20:48:16 INFO - PROCESS | 1640 | 20:51:17 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 20:51:18 INFO - PROCESS | 1640 | RSS: Main: 199020544 20:51:18 INFO - PROCESS | 1640 | 20:54:20 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 20:54:20 INFO - PROCESS | 1640 | RSS: Main: 202096640 20:54:20 INFO - PROCESS | 1640 | 20:57:23 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 20:57:24 INFO - PROCESS | 1640 | RSS: Main: 203608064 20:57:24 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 21:00:27 INFO - PROCESS | 1640 | RSS: Main: 206962688 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | __start_tp_report 21:00:27 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 21:00:27 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 21:00:27 INFO - PROCESS | 1640 | |i|pagename|runs| 21:00:27 INFO - PROCESS | 1640 | |0;dojo.html;2931.3551018775875;2973.658029326627;3518.4898189762484;3650.6166852480487;3652.981735091324;5845.795397161095;5910.408959104087;5924.555658325627;5927.40725927407;5981.790637327695;5895.263092113486;5900.586958912878;5978.850528736783;5983.311919129302;6011.819645410645;3844.730868839182;4241.384999175118;4862.808611890092;5349.304590403242;5470.91793623096;4635.605973492257;5066.834628035535;5324.281222035033;5467.726613669301;5517.117261238182;3948.763074215556;5332.946670533303;5374.220737992989;5468.9726551367185;5596.944030559704;5418.431064738224;5451.100568406208;5461.644993075437;5465.016297066526;5473.452654734535;5298.867528311785;5398.082326004549;5404.081306177959;5414.10667239905;5449.3188351456065;5925.585209035369;5949.434803693643;5959.612625179349;5970.701464926779;6140.171076904606;5387.649802762808;6074.331823499411;6229.906551401733;6291.528135389818;6308.369163083701;5246.239295302198;6326.68366581669;6330.778422755227;6331.113644089831;6349.952257877491;4827.131116399082;5853.94146058534;5876.529877609781;5880.059190529495;5943.197668314809;6279.058141278753;6310.495160387158;6318.905216421757;6341.936580634135;6341.985282354801;5218.425973142951;6296.307406185408;6340.080688300171;6345.524085693592;6354.9682251589375;5277.577793776489;6223.159873417121;6255.155554000152;6302.810915672537;6337.144485494491;6240.453633359608;6290.465310448663;6291.276503202099;6299.181106456132;6306.148669929501;5438.945610543844;5512.614117011771;6016.73648533804;6234.844128896814;6274.843128921813;5073.1122053640465;5082.517160869786;5094.286799848024;5101.413337466239;5136.332276803992;4553.089382123589;4559.931601025987;4561.2245918193985;4567.588916997486;4572.428446444194;5094.261332106824;5994.280686317583;6142.754289828446;6157.415045570664;6163.506919446433;5506.650870536744;6170.475509581735;6235.251769787564;6278.717457714414;6285.685715714288;6064.848378790565;6130.917941862977;6140.907886381708;6146.862830376394;6147.9855823788575;5538.6399884007415;5555.861103472445;5573.554115670737;5600.775968961196;5613.214150019001;6268.1851359323;6326.936730632727;6418.518611104279;6418.775342862978;6454.709538070892;6327.17746692927;6333.145025421509;6341.334159913143;6343.143675603735;6369.585976911486;6532.379423954717;6534.8366290843105;6537;6538.574992625654;6574;6169.352218017044;6176.258848938156;6201.472874805692;6290.056491526308;6312.621242725451;6280.591761535486;6291.874162516816;6300.621962682254;6314.084457753692;6323.399277068671;6146.078088286793;6197.690115494297;6224;6230.221222347207;6249.875002500015;5939.316978547522;6119.755209791737;6120.204373431604;6178.289496707936;6180.320164781961;6118.345080237214;6140;6190.164327815687;6217.4715149212825;6234;5837.737301821513;6259.123722678738;6274.498040156685;6301.905471417841;6322.3045464997895;6085.330613632408;6119.502553941512;6175.2589689237575;6219;6233.781817636361 21:00:27 INFO - PROCESS | 1640 | |1;ext.html;471.1754429747941;526.1476408218685;537.4679067722955;540.629668676956;542.2435702195437;40745.79627101864;42217.36673949893;42319.57680423195;42355;42434.57565424345;44166.116677666425;44646.77676611613;44709.32936005954;44729.7763511182;44907.87730306744;9761.267904907125;9801.705948821547;9802.901970980305;9882.110610045094;9902.158316543102;8364.330853531703;9719.125278724914;9721.708348749513;9734.172595329406;9859.35914165577;8579.828403431928;9576.952115239414;9707.90292097077;9781.559829807677;9821.950890245575;9705.805883882318;9749.171320437772;9784.266180036526;9789.853152202722;9834.901650983471;8287.461315014505;9689.660861869801;9719.514024298827;9773.951130244375;9873.963233860413;34115.6588434115;41594.75215743532;41743.58256417427;41992;42071.158576828595;3264.314493956272;3323.1858194742445;3344.6320904700465;3375.3924293627138;3387.9322413551836;29853.850730746206;30848.8457557713;31078.067657970296;32352.676473235202;32445.35109297801;2954.3795802881423;3293.7859039162377;3377.9831100844585;3405.080628230389;3494.2662040971427;493314;493739;505835;506146;507129;50359.992800143795;60283;60423.09365359523;60514;60589;63334.68332658307;65208;65543;65629.6718516414;65634;16823.41118060862;17089.829101708823;17324.913375433043;17330.740038899174;17349.7397539037;13297.401616927067;13357.332133393485;13386.062975591614;13450.932745336211;13468.191908485522;14366.640833979234;16654.83345166557;17288.13559322029;17391.56521086983;17400.303987840343;5591.720413979366;6111.96944015277;6254.21822272216;6399.424051835357;6460.095586617877;885.84054870124;1047.2302857399804;1047.8218702820539;1104.13877175803;1116.1628778416189;30843.69156308408;30900.22749431282;31139.532907006414;31348;31545.684543154734;959.8704174936433;983.7702871410737;1147.701597584634;1167.8481797366287;1193.2124797633521;13763.862361376458;16446.506604801874;17788.733169002477;17856.28574857017;17916.55208619795;690357;702848;703657;705738;706286;63655.68172159109;63861.680691597176;64844.67577662082;64879.67560162169;65102.674486627264;1434.6054834920271;1437.324457504971;1473.0425223604743;1474.3070756744312;1477.9113253204628;30451.543226851172;31018.534721978736;32861.67138328586;33399.16502087467;33512;57165.714171428874;58150.709246453494;58255;62792.05811912734;63257.367426325145;599953;605060;605478.9726051341;608300;611048;42263.366049509896;45551.54448455473;45647.31529027001;46105;46349;48224.03551929012;48386.75806620944;48831;49376.75311623419;49942;39597.60402395939;40001.79999099986;40006.59993400145;40136.996575085854;41887.790561047;39706.602933971444;39934.800325998185;40089;40675.3898691514;41091.17817643571;39002;39084.80457597694;39970;40138.598614013485;41901.95245118896;33184.336313274085;35929.64070359263;35951.820240898625;36045.27909441849;36282.4557631641;21772.67340989918;25409.364765881;26492.602610961236;26722.732772672025;27079.916803327647 21:00:27 INFO - PROCESS | 1640 | |2;jquery.html;340.15471553190326;391.1472988884235;398.4580969880962;399.7062159312903;416.1012213618583;18471.722924156147;20116.69824952623;20126.295579654696;20317.288894888683;20340.99999999998;19527.316543920966;19755.111020004097;20261;20429.387118386472;20450.18199272031;5231.607629427789;6040.124181993598;6134.478569321613;6185;6260.092286618437;6105;6109.602875813058;6153.876922461548;6157.138000679908;6188.350223226541;5883.970580147094;5954.732037058343;6038.154658347835;6064;6064.3026052003925;5547.72261386929;6018.4583387494895;6059.636421814683;6122.6632535210765;6125.019996800513;5261.421243663194;5270.314859068335;5293.894122117536;5309.469053094698;5318.30861987943;25272.73636318195;25490;25550.59471729054;25615;26040.609390858965;2099.5590925905576;2492.5638013347593;2591.948161036769;3034.8482575871117;3073.5205149312173;15215.467458638894;15399.307031183624;15789.973651712602;15905.602359940978;16378.754318685229;1868.318063906678;1896.753422055124;1900.5248687828043;1952.7851936286997;1955.07134111296;112518.20445655064;118308.40845795802;118413;118456.99999999914;118971;11245.437728113726;11449.828252576051;11671.533138674527;11798.646040618794;11931.522739090513;14173.645658858612;14214.715705685829;14232.003759736719;14252.287385630885;14283.071600345944;8667.626688609922;8892.8666070009;8922.464652120894;8945.15021072997;8998.910010899937;9028.503432311287;9059.456432614064;9108.453492790453;9110.498922559324;9308.30187735909;6752.628605426748;6847.246802851682;6851.965740171367;6862.9313706862295;6864.935934930094;1882.1436246507806;1908.7900330963582;1911.6559019376368;1917.1947781931344;1919.1555715485142;922.4695799914941;1043.1758910460803;1067.2315932528568;1068.9144868410508;1074.2533938912381;21021.89489052545;21342.466438338855;21748.565028699653;21982.230621928156;22047.22834700778;972.7665360313474;972.9854052189223;1007.9445630490246;1021.3667526133845;1058.5497867920844;11157.27477713946;11409.801970793113;11410.486528106256;11438.142139339583;11609.76780464386;478910;539234;550930;557120;560987;92582;102307.48846255722;104126;105330.47334763277;110341.44829275963;938.9718258506788;977.5747549815853;1004.834202356603;1021.6577446555339;1022.4018948914968;14141.858581414053;18460.538486537946;19301.710474342613;19637.214511419385;19784.505387365432;50006.74996624994;51194.48805512046;51435.228471573704;52555.2671619078;52654.73672631612;71744.64127679328;73914.89127663197;74895.62552187205;75057.24942750503;75144;7958.482698624803;8044.557549334842;8102.797430064295;8157.8776318354085;8307.6676932922;12697.365131743562;12704.87295127074;13012.089153759145;13169.209847409185;13320.200787952754;8943.329250306122;11114.333140011626;11126.610568630384;11133;11505.309681419141;10677.14582833387;10883.67348979532;10982.670519884416;10986.835197471884;11317.490712917774;9038.276937845088;10516.106130978647;10857.511411986638;11206.159538034843;11408.258463199865;7604.391648667983;7951.920480795118;7995.2804247618;8174.795630109057;8466.238038576557;11003.669889903316;11164.553417863195;11321.433928303388;11410.771784564427;11427.714307142389 21:00:27 INFO - PROCESS | 1640 | |3;mootools.html;1504.7366710825606;1525.046845721424;1737.8262173782616;1746.4062218845597;1860.0792607659212;7750.418718596114;7940.6823727051;8188.959055204723;8665.220130188281;8677.002144753347;8164.714235001776;8444.071152173256;8460.746177614681;8556;8559.358048146383;2341.7072865891764;2377.607694730376;2379.7382287948312;2382.8927698253533;2391.4260577461373;2376.1564644551195;2390.008146619151;2429.4776623026046;2430.86630235337;2431;2372.6796882420913;2385.42749740062;2390.8206884483734;2399.568077746005;2405.254371144942;2347.190219374313;2353.435175557871;2357.5284943011466;2380.226426411406;2384.3800611841048;2333.8366314358;2380.9523809523885;2400.5078958813574;2401.3156250468705;2404.074431343945;8482.236598706084;8593.914060859437;8668.52323122228;8680.782980425476;8700.390972631918;5192.92210616841;5332.093544097513;5333.973330133364;5352.4915133062295;5357.249985002103;3514.822534450962;3563.322968635951;3569.8750543731103;3607.278544291126;3632.600413954463;1018.2108865629123;1081.551156270147;1096.4901320885826;1115.9163062770244;1123.8651361836633;11104.666859994213;11153.553857845594;11169.553217871275;11175.441227938652;11213.943930280378;4556.407667003335;4650.767461626972;4732.597729193058;4736.928946065812;4741.5495527924795;9708.611655533841;9746.415215087118;9759.707208783748;9761.51192440375;9793.657221997197;5273.709945953009;5292.888493416425;5330.653507521999;5336.386315573681;5350.5184533392185;4385.693001489865;4426.734395936254;4811.374521312207;4837.274408838632;4863.3434486343895;3932.803359831997;3969;4021.235965166609;4058.2289365021143;4065.8780236592947;1286.6526037969695;1289.1620446709715;1299.6231092983119;1301.2387753164317;1304.0936549098285;922.7739203894954;931.1433481197315;936.9765755856158;937.2736129499692;950.7100334398075;5581.967336042845;5725.141228815628;5726.4273572642405;5884.205632239678;5954.3152537458745;896.8968568614563;916.3036092569695;919.103873723128;920.8388532007006;958.3291695812959;3835.827387767557;3920.4903362562686;3963.365861462152;3969.4641223435046;4007.39889016644;10081.747956301151;10264.076233139054;10303.227257955683;10364;10378.221633377527;9953.104220620178;9975.700728978143;10245.795084098276;10336.741581460374;10340.482975851177;7998.48009879356;8079.959600201962;8111;8112.837743245102;8213.178682131858;5647.745851436777;5653.123765816305;5704.657720536615;5813.912791308049;5823.621464604787;9342.299327550596;9552.474613896302;9648.75878103053;9667.516624168904;9682.467464289532;9432.905670943202;9688.564014619218;9846.753831154278;9860.802783944424;9861.605535778488;6404.679766011774;6569.24453687832;6676.432503237279;6864.347886950732;7014.737347277539;7795.142534321333;7874.881876771968;8137.145599711945;8162.306203972491;8178.46839955401;6332.240131184288;6454.709538070892;7312.670929808065;7346.742863999734;7416.666250018654;7156.320149585781;7197.964010180125;7213.567185968858;7242.203357630762;7308.8903666443985;6644.069830223676;6671.733130674718;6729.663516824041;6731;6745.190577130776;4261.595148460891;4450.933236001398;4860.73265970375;4904.705717656952;4947.010597880367;4816.590589799906;4838.540338667821;4859.659823812299;4989.950100498949;5045.470225626404 21:00:27 INFO - PROCESS | 1640 | |4;prototype.html;278.8215542053086;314.4230337330996;322.5516532020491;325.70198268584244;327.1151535097561;5343.851072019515;5523.806666766664;5561.082421400469;5579.46995035472;5627.9718601406985;4270.402143699885;5255.237990491377;5489.286392768944;5507.779688812443;5533.916991245134;4013.2776100301935;4187.748735075906;4215;4241.575842415749;4281.978590107045;3414.8634054637787;3842.3852183650483;3851.732394573946;4229;4280.373458085938;3284.5894263217097;3992.40113982902;4133.772642504661;4210.831566737342;4219.641330486897;4240.197915885737;4244.936325955113;4246.511651160125;4259.190753756776;4278.208531421681;3565.5721313442295;3673.9081522961874;3677.2829298286847;3685.7788532688123;3725.9194833498254;5345.358556973188;5391.245225668409;5393.649412788156;5445.401005889349;5457.727113644302;4090.6318431341324;4903.975480122613;4925.950740492585;4944.873704307147;4972;4778.95221047792;5413.566914646858;5600.411956744524;5863.179154918314;5881.705914704247;4093.406456063884;4104.240715467618;4119.299719047769;4126.731762435462;4134.441850350225;6071.446415188609;6150.630962142286;6157.2919114301985;6161.7535298588;6175.240056583852;4020.5979402060143;4121.649659778953;4161;4182.895427614334;4193.622573968358;3706.2772759312215;3742.8689995850013;4161.479815023122;4244.72409293395;4254.191703576311;4293.141371725605;4449.7997590108525;4703.811847526129;4895.73073480962;4915.410150781929;5207.989847817235;5535.916961245584;5813;5906.350301466835;5947.899638566879;1282.3267784413258;1339.2968691437075;1373.5604606526015;1378.5726424808342;1387.4172847404116;817.0807841178674;919.8574220995756;940.2571968145226;959.2757468111671;965.9372140810825;4500.549945005539;5086;5247.084814042847;5269.285153966444;5320.893582128336;5239.973800130975;5415.998040362546;5741.741621627037;5753.539716822645;5759;5053.84838454847;5115.948840511547;5258.053550360895;5276.788928442896;5637.548996080303;2601.6877974642784;2930.1100789990764;2930.457865294927;3031.378567393679;3064.2492589315316;4906.0433215522635;5734.5125664319;5751.309842818889;5755.776765011607;6019.488343490765;5130.51260130287;5481.561475081984;5521.834344969658;5832.708364581754;5912.822615321547;5569.832905012856;5999.340072592098;6008.879822403527;6029.306629737549;6040;5358.812441564526;5370.301860758076;5776.508996735157;5817.680027598522;5824.5631577631;5076.949230507647;5262.526612492123;5285;5538.695371754592;5713.400092990176;5316.202569614588;5425.620206585501;5918.254115036161;5988.520918326436;6033.637981721111;3253.5282384054653;3557.875474358385;3701.4817925489915;3856.479375284413;3870.83874837542;5876.294844618673;6049.334573197033;6073.726682299395;6076.51387888959;6105.45050945417;4960.305557222064;5899.46904778572;5907.172995780508;5911;5929.835610002405;5644.520215781705;5734.655920644775;5919.625813286558;5922.73347699346;5944.197533332945;5494.81861399801;5604.859878502907;5733.799317023884;5753.598497149767;5755.079187330007;5864.413558644102;5926;5961.52307815365;5986.580939334205;6000.009998350398;4283.957160428356;4754.31062495943;5120.590352771695;5743.798967036134;5768.596198266081 21:00:27 INFO - PROCESS | 1640 | |5;yui.html;629.3454807000719;778.0313509680449;788.1409263902345;811.4847072109211;816.6978218059319;12723.872761272372;14585.34365953532;15186.620334491643;15401.768973465407;15403.614909627266;12694.873051269484;13156.289682341283;13807.723845523084;13954.651133721662;14045.50840720575;853.138330286412;853.2875049333813;884.6594061286387;891.7101941868887;895.3687650206622;868.4138206710477;886.3706768194589;896.2292428511507;899.7570655922897;902.7111324376202;922.8800255966748;931.1293940165966;939.0327962198946;939.9765005874874;941.3457646935383;931.4504442379028;932.7668082979255;932.9067093290616;934.5794392523392;937.0863408177041;684.9178098628197;688.3667026335783;693.628908533937;701.6632016632045;702.8769965256059;14426.783598246035;14488.34802433893;14729.337179826827;14795.630109247248;14834.554963351116;12678.429470673842;13654;14036.298185090807;14235.145891246559;14314.64213394663;11773.882261177365;11947.641570752892;12580.748385032248;12955.09314347996;13038.348082595832;4558.8632341029825;4621.306803979396;4652.278896771006;4678.929816052762;4785.904281914377;94970;96361.5545766814;96918.51540742321;99974.50012749889;100404;9452.95273523628;10553.947230263799;10864.619738309122;10888.891111088946;10894.291871028357;19874.801251987294;19914.701279480818;20052.498687532636;20153.99230038492;20327.8983605084;7761.495502792301;7779.805504862424;7836.804079898048;7905.960470197612;8005.599720014093;3703.2778608171147;4339.5660433956355;4441.755703436277;4476.955230447719;4498.415206023196;3035.134986528873;3341.565596472443;3414.5219669246326;3419.2135808764124;3452.9654703453148;537.9946200538022;652.1977967100398;656.4518626946553;657.9046038324512;666.8862998792029;574.5001848391926;593.1606776411329;619.6406084470996;632.5761739634456;649.0912722188879;8460.238578527962;8508.617112229964;8932.883389576304;9103;9723.756906077264;636.3032479435011;656.8095252376853;657.9769708060195;671.5198632977445;716.9032180655649;3931.488906442144;4034.829899329162;4230.978845105755;4317.9136417271475;4493.910121797546;70560;100344.49827750814;107227;109566;114593.4270328643;71660;90981.09018909858;99174.51238231432;103856.48071759593;104752;79796;80582.59708701419;87026;90371.19257614811;90496;9376;9702.951485242811;9925;9928.354656947275;9941.701748947544;44022.339664905696;52275.73862130665;53035.20447193372;56950;58437.662493501484;58116.70941645265;71296.28703712896;72493;86207;87570;10576.418296993432;12901.290429026118;12952;13239.735205295647;13270.81288213634;14417;14612.561623151323;14742.48401305992;14782.260886955824;14931.477398291008;13548.932255338661;14001.369938353002;14012.859871401155;14824.925875370554;14947.476838310607;13263.933680331536;13656.317184140555;13775;13930.303484825921;14507.347169377614;12970.93514532459;13053.738925221252;13260.602181934557;13330;13383.130096544082;10814.728906929318;10977.506012229629;11315.886841131483;11700.941495292469;11715.472803723682;11426;11575.479103440197;11783;11785.116116291141;11810 21:00:27 INFO - PROCESS | 1640 | __end_tp_report 21:00:27 INFO - PROCESS | 1640 | __start_cc_report 21:00:27 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,352 21:00:27 INFO - PROCESS | 1640 | __end_cc_report 21:00:27 INFO - PROCESS | 1640 | __startTimestamp1486530027285__endTimestamp 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | ------- Summary: start ------- 21:00:27 INFO - PROCESS | 1640 | Number of tests: 6 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5809.77 Median:6120.20 stddev:698.65 (11.4%) stddev-sans-first:663.30 21:00:27 INFO - PROCESS | 1640 | Values: 2931.4 2973.7 3518.5 3650.6 3653.0 5845.8 5910.4 5924.6 5927.4 5981.8 5895.3 5900.6 5978.9 5983.3 6011.8 3844.7 4241.4 4862.8 5349.3 5470.9 4635.6 5066.8 5324.3 5467.7 5517.1 3948.8 5332.9 5374.2 5469.0 5596.9 5418.4 5451.1 5461.6 5465.0 5473.5 5298.9 5398.1 5404.1 5414.1 5449.3 5925.6 5949.4 5959.6 5970.7 6140.2 5387.6 6074.3 6229.9 6291.5 6308.4 5246.2 6326.7 6330.8 6331.1 6350.0 4827.1 5853.9 5876.5 5880.1 5943.2 6279.1 6310.5 6318.9 6341.9 6342.0 5218.4 6296.3 6340.1 6345.5 6355.0 5277.6 6223.2 6255.2 6302.8 6337.1 6240.5 6290.5 6291.3 6299.2 6306.1 5438.9 5512.6 6016.7 6234.8 6274.8 5073.1 5082.5 5094.3 5101.4 5136.3 4553.1 4559.9 4561.2 4567.6 4572.4 5094.3 5994.3 6142.8 6157.4 6163.5 5506.7 6170.5 6235.3 6278.7 6285.7 6064.8 6130.9 6140.9 6146.9 6148.0 5538.6 5555.9 5573.6 5600.8 5613.2 6268.2 6326.9 6418.5 6418.8 6454.7 6327.2 6333.1 6341.3 6343.1 6369.6 6532.4 6534.8 6537.0 6538.6 6574.0 6169.4 6176.3 6201.5 6290.1 6312.6 6280.6 6291.9 6300.6 6314.1 6323.4 6146.1 6197.7 6224.0 6230.2 6249.9 5939.3 6119.8 6120.2 6178.3 6180.3 6118.3 6140.0 6190.2 6217.5 6234.0 5837.7 6259.1 6274.5 6301.9 6322.3 6085.3 6119.5 6175.3 6219.0 6233.8 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:74533.77 Median:42337.29 stddev:162693.52 (384.3%) stddev-sans-first:163054.88 21:00:27 INFO - PROCESS | 1640 | Values: 471.2 526.1 537.5 540.6 542.2 40745.8 42217.4 42319.6 42355.0 42434.6 44166.1 44646.8 44709.3 44729.8 44907.9 9761.3 9801.7 9802.9 9882.1 9902.2 8364.3 9719.1 9721.7 9734.2 9859.4 8579.8 9577.0 9707.9 9781.6 9822.0 9705.8 9749.2 9784.3 9789.9 9834.9 8287.5 9689.7 9719.5 9774.0 9874.0 34115.7 41594.8 41743.6 41992.0 42071.2 3264.3 3323.2 3344.6 3375.4 3387.9 29853.9 30848.8 31078.1 32352.7 32445.4 2954.4 3293.8 3378.0 3405.1 3494.3 493314.0 493739.0 505835.0 506146.0 507129.0 50360.0 60283.0 60423.1 60514.0 60589.0 63334.7 65208.0 65543.0 65629.7 65634.0 16823.4 17089.8 17324.9 17330.7 17349.7 13297.4 13357.3 13386.1 13450.9 13468.2 14366.6 16654.8 17288.1 17391.6 17400.3 5591.7 6112.0 6254.2 6399.4 6460.1 885.8 1047.2 1047.8 1104.1 1116.2 30843.7 30900.2 31139.5 31348.0 31545.7 959.9 983.8 1147.7 1167.8 1193.2 13763.9 16446.5 17788.7 17856.3 17916.6 690357.0 702848.0 703657.0 705738.0 706286.0 63655.7 63861.7 64844.7 64879.7 65102.7 1434.6 1437.3 1473.0 1474.3 1477.9 30451.5 31018.5 32861.7 33399.2 33512.0 57165.7 58150.7 58255.0 62792.1 63257.4 599953.0 605060.0 605479.0 608300.0 611048.0 42263.4 45551.5 45647.3 46105.0 46349.0 48224.0 48386.8 48831.0 49376.8 49942.0 39597.6 40001.8 40006.6 40137.0 41887.8 39706.6 39934.8 40089.0 40675.4 41091.2 39002.0 39084.8 39970.0 40138.6 41902.0 33184.3 35929.6 35951.8 36045.3 36282.5 21772.7 25409.4 26492.6 26722.7 27079.9 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:32742.65 Median:21865.40 stddev:89671.77 (410.1%) stddev-sans-first:89890.31 21:00:27 INFO - PROCESS | 1640 | Values: 340.2 391.1 398.5 399.7 416.1 18471.7 20116.7 20126.3 20317.3 20341.0 19527.3 19755.1 20261.0 20429.4 20450.2 5231.6 6040.1 6134.5 6185.0 6260.1 6105.0 6109.6 6153.9 6157.1 6188.4 5884.0 5954.7 6038.2 6064.0 6064.3 5547.7 6018.5 6059.6 6122.7 6125.0 5261.4 5270.3 5293.9 5309.5 5318.3 25272.7 25490.0 25550.6 25615.0 26040.6 2099.6 2492.6 2591.9 3034.8 3073.5 15215.5 15399.3 15790.0 15905.6 16378.8 1868.3 1896.8 1900.5 1952.8 1955.1 112518.2 118308.4 118413.0 118457.0 118971.0 11245.4 11449.8 11671.5 11798.6 11931.5 14173.6 14214.7 14232.0 14252.3 14283.1 8667.6 8892.9 8922.5 8945.2 8998.9 9028.5 9059.5 9108.5 9110.5 9308.3 6752.6 6847.2 6852.0 6862.9 6864.9 1882.1 1908.8 1911.7 1917.2 1919.2 922.5 1043.2 1067.2 1068.9 1074.3 21021.9 21342.5 21748.6 21982.2 22047.2 972.8 973.0 1007.9 1021.4 1058.5 11157.3 11409.8 11410.5 11438.1 11609.8 478910.0 539234.0 550930.0 557120.0 560987.0 92582.0 102307.5 104126.0 105330.5 110341.4 939.0 977.6 1004.8 1021.7 1022.4 14141.9 18460.5 19301.7 19637.2 19784.5 50006.7 51194.5 51435.2 52555.3 52654.7 71744.6 73914.9 74895.6 75057.2 75144.0 7958.5 8044.6 8102.8 8157.9 8307.7 12697.4 12704.9 13012.1 13169.2 13320.2 8943.3 11114.3 11126.6 11133.0 11505.3 10677.1 10883.7 10982.7 10986.8 11317.5 9038.3 10516.1 10857.5 11206.2 11408.3 7604.4 7951.9 7995.3 8174.8 8466.2 11003.7 11164.6 11321.4 11410.8 11427.7 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5560.39 Median:5311.77 stddev:3036.94 (57.2%) stddev-sans-first:3030.16 21:00:27 INFO - PROCESS | 1640 | Values: 1504.7 1525.0 1737.8 1746.4 1860.1 7750.4 7940.7 8189.0 8665.2 8677.0 8164.7 8444.1 8460.7 8556.0 8559.4 2341.7 2377.6 2379.7 2382.9 2391.4 2376.2 2390.0 2429.5 2430.9 2431.0 2372.7 2385.4 2390.8 2399.6 2405.3 2347.2 2353.4 2357.5 2380.2 2384.4 2333.8 2381.0 2400.5 2401.3 2404.1 8482.2 8593.9 8668.5 8680.8 8700.4 5192.9 5332.1 5334.0 5352.5 5357.2 3514.8 3563.3 3569.9 3607.3 3632.6 1018.2 1081.6 1096.5 1115.9 1123.9 11104.7 11153.6 11169.6 11175.4 11213.9 4556.4 4650.8 4732.6 4736.9 4741.5 9708.6 9746.4 9759.7 9761.5 9793.7 5273.7 5292.9 5330.7 5336.4 5350.5 4385.7 4426.7 4811.4 4837.3 4863.3 3932.8 3969.0 4021.2 4058.2 4065.9 1286.7 1289.2 1299.6 1301.2 1304.1 922.8 931.1 937.0 937.3 950.7 5582.0 5725.1 5726.4 5884.2 5954.3 896.9 916.3 919.1 920.8 958.3 3835.8 3920.5 3963.4 3969.5 4007.4 10081.7 10264.1 10303.2 10364.0 10378.2 9953.1 9975.7 10245.8 10336.7 10340.5 7998.5 8080.0 8111.0 8112.8 8213.2 5647.7 5653.1 5704.7 5813.9 5823.6 9342.3 9552.5 9648.8 9667.5 9682.5 9432.9 9688.6 9846.8 9860.8 9861.6 6404.7 6569.2 6676.4 6864.3 7014.7 7795.1 7874.9 8137.1 8162.3 8178.5 6332.2 6454.7 7312.7 7346.7 7416.7 7156.3 7198.0 7213.6 7242.2 7308.9 6644.1 6671.7 6729.7 6731.0 6745.2 4261.6 4450.9 4860.7 4904.7 4947.0 4816.6 4838.5 4859.7 4990.0 5045.5 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4679.80 Median:5280.89 stddev:1435.02 (27.2%) stddev-sans-first:1400.51 21:00:27 INFO - PROCESS | 1640 | Values: 278.8 314.4 322.6 325.7 327.1 5343.9 5523.8 5561.1 5579.5 5628.0 4270.4 5255.2 5489.3 5507.8 5533.9 4013.3 4187.7 4215.0 4241.6 4282.0 3414.9 3842.4 3851.7 4229.0 4280.4 3284.6 3992.4 4133.8 4210.8 4219.6 4240.2 4244.9 4246.5 4259.2 4278.2 3565.6 3673.9 3677.3 3685.8 3725.9 5345.4 5391.2 5393.6 5445.4 5457.7 4090.6 4904.0 4926.0 4944.9 4972.0 4779.0 5413.6 5600.4 5863.2 5881.7 4093.4 4104.2 4119.3 4126.7 4134.4 6071.4 6150.6 6157.3 6161.8 6175.2 4020.6 4121.6 4161.0 4182.9 4193.6 3706.3 3742.9 4161.5 4244.7 4254.2 4293.1 4449.8 4703.8 4895.7 4915.4 5208.0 5535.9 5813.0 5906.4 5947.9 1282.3 1339.3 1373.6 1378.6 1387.4 817.1 919.9 940.3 959.3 965.9 4500.5 5086.0 5247.1 5269.3 5320.9 5240.0 5416.0 5741.7 5753.5 5759.0 5053.8 5115.9 5258.1 5276.8 5637.5 2601.7 2930.1 2930.5 3031.4 3064.2 4906.0 5734.5 5751.3 5755.8 6019.5 5130.5 5481.6 5521.8 5832.7 5912.8 5569.8 5999.3 6008.9 6029.3 6040.0 5358.8 5370.3 5776.5 5817.7 5824.6 5076.9 5262.5 5285.0 5538.7 5713.4 5316.2 5425.6 5918.3 5988.5 6033.6 3253.5 3557.9 3701.5 3856.5 3870.8 5876.3 6049.3 6073.7 6076.5 6105.5 4960.3 5899.5 5907.2 5911.0 5929.8 5644.5 5734.7 5919.6 5922.7 5944.2 5494.8 5604.9 5733.8 5753.6 5755.1 5864.4 5926.0 5961.5 5986.6 6000.0 4284.0 4754.3 5120.6 5743.8 5768.6 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20758.98 Median:4496.16 stddev:30048.38 (668.3%) stddev-sans-first:30094.66 21:00:27 INFO - PROCESS | 1640 | Values: 629.3 778.0 788.1 811.5 816.7 12723.9 14585.3 15186.6 15401.8 15403.6 12694.9 13156.3 13807.7 13954.7 14045.5 853.1 853.3 884.7 891.7 895.4 868.4 886.4 896.2 899.8 902.7 922.9 931.1 939.0 940.0 941.3 931.5 932.8 932.9 934.6 937.1 684.9 688.4 693.6 701.7 702.9 14426.8 14488.3 14729.3 14795.6 14834.6 12678.4 13654.0 14036.3 14235.1 14314.6 11773.9 11947.6 12580.7 12955.1 13038.3 4558.9 4621.3 4652.3 4678.9 4785.9 94970.0 96361.6 96918.5 99974.5 100404.0 9453.0 10553.9 10864.6 10888.9 10894.3 19874.8 19914.7 20052.5 20154.0 20327.9 7761.5 7779.8 7836.8 7906.0 8005.6 3703.3 4339.6 4441.8 4477.0 4498.4 3035.1 3341.6 3414.5 3419.2 3453.0 538.0 652.2 656.5 657.9 666.9 574.5 593.2 619.6 632.6 649.1 8460.2 8508.6 8932.9 9103.0 9723.8 636.3 656.8 658.0 671.5 716.9 3931.5 4034.8 4231.0 4317.9 4493.9 70560.0 100344.5 107227.0 109566.0 114593.4 71660.0 90981.1 99174.5 103856.5 104752.0 79796.0 80582.6 87026.0 90371.2 90496.0 9376.0 9703.0 9925.0 9928.4 9941.7 44022.3 52275.7 53035.2 56950.0 58437.7 58116.7 71296.3 72493.0 86207.0 87570.0 10576.4 12901.3 12952.0 13239.7 13270.8 14417.0 14612.6 14742.5 14782.3 14931.5 13548.9 14001.4 14012.9 14824.9 14947.5 13263.9 13656.3 13775.0 13930.3 14507.3 12970.9 13053.7 13260.6 13330.0 13383.1 10814.7 10977.5 11315.9 11700.9 11715.5 11426.0 11575.5 11783.0 11785.1 11810.0 21:00:27 INFO - PROCESS | 1640 | -------- Summary: end -------- 21:00:27 INFO - PROCESS | 1640 | 21:00:27 INFO - TEST-INFO | 1640: exit 0 21:00:27 INFO - TEST-OK | dromaeo_css | took 1114686ms 21:00:27 INFO - TEST-START | kraken 21:00:27 INFO - operating with platform_type : mac_ 21:00:27 INFO - Initialising browser for kraken test... 21:00:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpWyPn1l/profile http://localhost:49246/getInfo.html) 21:00:30 INFO - PROCESS | 1652 | 2017-02-07 21:00:30.602 plugin-container[1654:9915] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 21:00:30 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:30 INFO - PROCESS | 1652 | 2017-02-07 21:00:30.602 plugin-container[1654:9915] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 21:00:30 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:30 INFO - PROCESS | 1652 | 2017-02-07 21:00:30.602 plugin-container[1654:9915] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:00:30 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 21:00:30 INFO - PROCESS | 1652 | colorDepth:24 21:00:30 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 21:00:30 INFO - PROCESS | 1652 | __metrics 21:00:30 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 21:00:31 INFO - TEST-INFO | 1652: exit 0 21:00:31 INFO - Browser initialized. 21:00:31 INFO - Running cycle 1/1 for kraken test... 21:00:31 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpWyPn1l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:00:34 INFO - PROCESS | 1655 | 2017-02-07 21:00:34.051 plugin-container[1656:10037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 21:00:34 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:34 INFO - PROCESS | 1655 | 2017-02-07 21:00:34.052 plugin-container[1656:10037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8523, name = 'com.apple.CFPasteboardClient' 21:00:34 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:34 INFO - PROCESS | 1655 | 2017-02-07 21:00:34.052 plugin-container[1656:10037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:00:43 INFO - PROCESS | 1655 | RSS: Main: 176652288 21:00:43 INFO - PROCESS | 1655 | 21:00:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 21:00:47 INFO - PROCESS | 1655 | RSS: Main: 190390272 21:00:47 INFO - PROCESS | 1655 | 21:00:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 21:00:50 INFO - PROCESS | 1655 | RSS: Main: 197804032 21:00:50 INFO - PROCESS | 1655 | 21:00:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 21:00:54 INFO - PROCESS | 1655 | RSS: Main: 201314304 21:00:54 INFO - PROCESS | 1655 | 21:00:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 21:00:57 INFO - PROCESS | 1655 | RSS: Main: 202870784 21:00:57 INFO - PROCESS | 1655 | 21:00:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:00:59 INFO - PROCESS | 1655 | RSS: Main: 203657216 21:00:59 INFO - PROCESS | 1655 | 21:01:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 21:01:03 INFO - PROCESS | 1655 | RSS: Main: 207593472 21:01:03 INFO - PROCESS | 1655 | 21:01:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 21:01:06 INFO - PROCESS | 1655 | RSS: Main: 207167488 21:01:06 INFO - PROCESS | 1655 | 21:01:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 21:01:10 INFO - PROCESS | 1655 | RSS: Main: 206655488 21:01:10 INFO - PROCESS | 1655 | 21:01:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:01:12 INFO - PROCESS | 1655 | RSS: Main: 206016512 21:01:12 INFO - PROCESS | 1655 | 21:01:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:01:15 INFO - PROCESS | 1655 | RSS: Main: 206090240 21:01:15 INFO - PROCESS | 1655 | 21:01:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:01:18 INFO - PROCESS | 1655 | RSS: Main: 205873152 21:01:18 INFO - PROCESS | 1655 | 21:01:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:01:21 INFO - PROCESS | 1655 | RSS: Main: 205938688 21:01:21 INFO - PROCESS | 1655 | 21:01:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:01:25 INFO - PROCESS | 1655 | RSS: Main: 206258176 21:01:25 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 21:01:27 INFO - PROCESS | 1655 | RSS: Main: 207872000 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | __start_tp_report 21:01:27 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 21:01:27 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 21:01:27 INFO - PROCESS | 1655 | |i|pagename|runs| 21:01:27 INFO - PROCESS | 1655 | |0;ai-astar;106;117;118;129;175;164;116;123;105;175 21:01:27 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;116;147;133;140;141;111;111;134;111 21:01:27 INFO - PROCESS | 1655 | |2;audio-dft;196;196;197;198;196;194;192;195;197;196 21:01:27 INFO - PROCESS | 1655 | |3;audio-fft;77;60;60;73;75;54;52;62;106;83 21:01:27 INFO - PROCESS | 1655 | |4;audio-oscillator;89;69;88;73;70;73;86;69;74;88 21:01:27 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;130;94;75;137;133;89;87;89;99 21:01:27 INFO - PROCESS | 1655 | |6;imaging-darkroom;99;99;99;99;100;97;97;100;99;106 21:01:27 INFO - PROCESS | 1655 | |7;imaging-desaturate;88;93;86;87;102;72;147;148;94;143 21:01:27 INFO - PROCESS | 1655 | |8;json-parse-financial;56;55;55;55;57;59;62;91;92;86 21:01:27 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;68;75;67;82;81;112;102;103;75;69 21:01:27 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;82;81;82;82;82;83;84;84;82;85 21:01:27 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;139;139;135;142;158;148;149;165;185;139 21:01:27 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;199;238;166;162;176;235;168;163;209;221 21:01:27 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;66;67;65;68;66;77;79;87;85 21:01:27 INFO - PROCESS | 1655 | __end_tp_report 21:01:27 INFO - PROCESS | 1655 | __start_cc_report 21:01:27 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,899 21:01:27 INFO - PROCESS | 1655 | __end_cc_report 21:01:27 INFO - PROCESS | 1655 | __startTimestamp1486530087550__endTimestamp 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | ------- Summary: start ------- 21:01:27 INFO - PROCESS | 1655 | Number of tests: 14 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:132.80 Median:126.00 stddev:27.66 (22.0%) stddev-sans-first:27.59 21:01:27 INFO - PROCESS | 1655 | Values: 106.0 117.0 118.0 129.0 175.0 164.0 116.0 123.0 105.0 175.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:137.00 stddev:14.68 (10.7%) stddev-sans-first:14.74 21:01:27 INFO - PROCESS | 1655 | Values: 142.0 116.0 147.0 133.0 140.0 141.0 111.0 111.0 134.0 111.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:195.70 Median:196.00 stddev:1.70 (0.9%) stddev-sans-first:1.80 21:01:27 INFO - PROCESS | 1655 | Values: 196.0 196.0 197.0 198.0 196.0 194.0 192.0 195.0 197.0 196.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:70.20 Median:67.50 stddev:16.30 (24.2%) stddev-sans-first:17.10 21:01:27 INFO - PROCESS | 1655 | Values: 77.0 60.0 60.0 73.0 75.0 54.0 52.0 62.0 106.0 83.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.00 stddev:8.67 (10.8%) stddev-sans-first:8.22 21:01:27 INFO - PROCESS | 1655 | Values: 89.0 69.0 88.0 73.0 70.0 73.0 86.0 69.0 74.0 88.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:102.10 Median:88.50 stddev:22.44 (25.4%) stddev-sans-first:23.21 21:01:27 INFO - PROCESS | 1655 | Values: 88.0 130.0 94.0 75.0 137.0 133.0 89.0 87.0 89.0 99.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:99.50 Median:99.00 stddev:2.51 (2.5%) stddev-sans-first:2.65 21:01:27 INFO - PROCESS | 1655 | Values: 99.0 99.0 99.0 99.0 100.0 97.0 97.0 100.0 99.0 106.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:106.00 Median:86.50 stddev:28.64 (33.1%) stddev-sans-first:29.63 21:01:27 INFO - PROCESS | 1655 | Values: 88.0 93.0 86.0 87.0 102.0 72.0 147.0 148.0 94.0 143.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:60.50 stddev:16.00 (26.4%) stddev-sans-first:16.48 21:01:27 INFO - PROCESS | 1655 | Values: 56.0 55.0 55.0 55.0 57.0 59.0 62.0 91.0 92.0 86.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:72.00 stddev:16.37 (22.7%) stddev-sans-first:16.38 21:01:27 INFO - PROCESS | 1655 | Values: 68.0 75.0 67.0 82.0 81.0 112.0 102.0 103.0 75.0 69.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.50 stddev:1.25 (1.5%) stddev-sans-first:1.30 21:01:27 INFO - PROCESS | 1655 | Values: 82.0 81.0 82.0 82.0 82.0 83.0 84.0 84.0 82.0 85.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:148.50 stddev:15.53 (10.5%) stddev-sans-first:15.96 21:01:27 INFO - PROCESS | 1655 | Values: 139.0 139.0 135.0 142.0 158.0 148.0 149.0 165.0 185.0 139.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.70 Median:204.00 stddev:30.49 (14.9%) stddev-sans-first:32.27 21:01:27 INFO - PROCESS | 1655 | Values: 199.0 238.0 166.0 162.0 176.0 235.0 168.0 163.0 209.0 221.0 21:01:27 INFO - PROCESS | 1655 | 21:01:27 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:72.50 stddev:8.43 (11.6%) stddev-sans-first:8.76 21:01:27 INFO - PROCESS | 1655 | Values: 68.0 66.0 67.0 65.0 68.0 66.0 77.0 79.0 87.0 85.0 21:01:27 INFO - PROCESS | 1655 | -------- Summary: end -------- 21:01:27 INFO - PROCESS | 1655 | 21:01:28 INFO - TEST-INFO | 1655: exit 0 21:01:28 INFO - TEST-OK | kraken | took 60692ms 21:01:28 INFO - SUITE-END | took 1175s 21:01:28 INFO - Completed test suite (00:19:35) 21:01:28 INFO - javascript benchmark 21:01:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7442.819949248975, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2931.3551018775875, 2973.658029326627, 3518.4898189762484, 3650.6166852480487, 3652.981735091324, 5845.795397161095, 5910.408959104087, 5924.555658325627, 5927.40725927407, 5981.790637327695, 5895.263092113486, 5900.586958912878, 5978.850528736783, 5983.311919129302, 6011.819645410645, 3844.730868839182, 4241.384999175118, 4862.808611890092, 5349.304590403242, 5470.91793623096, 4635.605973492257, 5066.834628035535, 5324.281222035033, 5467.726613669301, 5517.117261238182, 3948.763074215556, 5332.946670533303, 5374.220737992989, 5468.9726551367185, 5596.944030559704, 5418.431064738224, 5451.100568406208, 5461.644993075437, 5465.016297066526, 5473.452654734535, 5298.867528311785, 5398.082326004549, 5404.081306177959, 5414.10667239905, 5449.3188351456065, 5925.585209035369, 5949.434803693643, 5959.612625179349, 5970.701464926779, 6140.171076904606, 5387.649802762808, 6074.331823499411, 6229.906551401733, 6291.528135389818, 6308.369163083701, 5246.239295302198, 6326.68366581669, 6330.778422755227, 6331.113644089831, 6349.952257877491, 4827.131116399082, 5853.94146058534, 5876.529877609781, 5880.059190529495, 5943.197668314809, 6279.058141278753, 6310.495160387158, 6318.905216421757, 6341.936580634135, 6341.985282354801, 5218.425973142951, 6296.307406185408, 6340.080688300171, 6345.524085693592, 6354.9682251589375, 5277.577793776489, 6223.159873417121, 6255.155554000152, 6302.810915672537, 6337.144485494491, 6240.453633359608, 6290.465310448663, 6291.276503202099, 6299.181106456132, 6306.148669929501, 5438.945610543844, 5512.614117011771, 6016.73648533804, 6234.844128896814, 6274.843128921813, 5073.1122053640465, 5082.517160869786, 5094.286799848024, 5101.413337466239, 5136.332276803992, 4553.089382123589, 4559.931601025987, 4561.2245918193985, 4567.588916997486, 4572.428446444194, 5094.261332106824, 5994.280686317583, 6142.754289828446, 6157.415045570664, 6163.506919446433, 5506.650870536744, 6170.475509581735, 6235.251769787564, 6278.717457714414, 6285.685715714288, 6064.848378790565, 6130.917941862977, 6140.907886381708, 6146.862830376394, 6147.9855823788575, 5538.6399884007415, 5555.861103472445, 5573.554115670737, 5600.775968961196, 5613.214150019001, 6268.1851359323, 6326.936730632727, 6418.518611104279, 6418.775342862978, 6454.709538070892, 6327.17746692927, 6333.145025421509, 6341.334159913143, 6343.143675603735, 6369.585976911486, 6532.379423954717, 6534.8366290843105, 6537.0, 6538.574992625654, 6574.0, 6169.352218017044, 6176.258848938156, 6201.472874805692, 6290.056491526308, 6312.621242725451, 6280.591761535486, 6291.874162516816, 6300.621962682254, 6314.084457753692, 6323.399277068671, 6146.078088286793, 6197.690115494297, 6224.0, 6230.221222347207, 6249.875002500015, 5939.316978547522, 6119.755209791737, 6120.204373431604, 6178.289496707936, 6180.320164781961, 6118.345080237214, 6140.0, 6190.164327815687, 6217.4715149212825, 6234.0, 5837.737301821513, 6259.123722678738, 6274.498040156685, 6301.905471417841, 6322.3045464997895, 6085.330613632408, 6119.502553941512, 6175.2589689237575, 6219.0, 6233.781817636361], "value": 5766.486038362089, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.1754429747941, 526.1476408218685, 537.4679067722955, 540.629668676956, 542.2435702195437, 40745.79627101864, 42217.36673949893, 42319.57680423195, 42355.0, 42434.57565424345, 44166.116677666425, 44646.77676611613, 44709.32936005954, 44729.7763511182, 44907.87730306744, 9761.267904907125, 9801.705948821547, 9802.901970980305, 9882.110610045094, 9902.158316543102, 8364.330853531703, 9719.125278724914, 9721.708348749513, 9734.172595329406, 9859.35914165577, 8579.828403431928, 9576.952115239414, 9707.90292097077, 9781.559829807677, 9821.950890245575, 9705.805883882318, 9749.171320437772, 9784.266180036526, 9789.853152202722, 9834.901650983471, 8287.461315014505, 9689.660861869801, 9719.514024298827, 9773.951130244375, 9873.963233860413, 34115.6588434115, 41594.75215743532, 41743.58256417427, 41992.0, 42071.158576828595, 3264.314493956272, 3323.1858194742445, 3344.6320904700465, 3375.3924293627138, 3387.9322413551836, 29853.850730746206, 30848.8457557713, 31078.067657970296, 32352.676473235202, 32445.35109297801, 2954.3795802881423, 3293.7859039162377, 3377.9831100844585, 3405.080628230389, 3494.2662040971427, 493314.0, 493739.0, 505835.0, 506146.0, 507129.0, 50359.992800143795, 60283.0, 60423.09365359523, 60514.0, 60589.0, 63334.68332658307, 65208.0, 65543.0, 65629.6718516414, 65634.0, 16823.41118060862, 17089.829101708823, 17324.913375433043, 17330.740038899174, 17349.7397539037, 13297.401616927067, 13357.332133393485, 13386.062975591614, 13450.932745336211, 13468.191908485522, 14366.640833979234, 16654.83345166557, 17288.13559322029, 17391.56521086983, 17400.303987840343, 5591.720413979366, 6111.96944015277, 6254.21822272216, 6399.424051835357, 6460.095586617877, 885.84054870124, 1047.2302857399804, 1047.8218702820539, 1104.13877175803, 1116.1628778416189, 30843.69156308408, 30900.22749431282, 31139.532907006414, 31348.0, 31545.684543154734, 959.8704174936433, 983.7702871410737, 1147.701597584634, 1167.8481797366287, 1193.2124797633521, 13763.862361376458, 16446.506604801874, 17788.733169002477, 17856.28574857017, 17916.55208619795, 690357.0, 702848.0, 703657.0, 705738.0, 706286.0, 63655.68172159109, 63861.680691597176, 64844.67577662082, 64879.67560162169, 65102.674486627264, 1434.6054834920271, 1437.324457504971, 1473.0425223604743, 1474.3070756744312, 1477.9113253204628, 30451.543226851172, 31018.534721978736, 32861.67138328586, 33399.16502087467, 33512.0, 57165.714171428874, 58150.709246453494, 58255.0, 62792.05811912734, 63257.367426325145, 599953.0, 605060.0, 605478.9726051341, 608300.0, 611048.0, 42263.366049509896, 45551.54448455473, 45647.31529027001, 46105.0, 46349.0, 48224.03551929012, 48386.75806620944, 48831.0, 49376.75311623419, 49942.0, 39597.60402395939, 40001.79999099986, 40006.59993400145, 40136.996575085854, 41887.790561047, 39706.602933971444, 39934.800325998185, 40089.0, 40675.3898691514, 41091.17817643571, 39002.0, 39084.80457597694, 39970.0, 40138.598614013485, 41901.95245118896, 33184.336313274085, 35929.64070359263, 35951.820240898625, 36045.27909441849, 36282.4557631641, 21772.67340989918, 25409.364765881, 26492.602610961236, 26722.732772672025, 27079.916803327647], "value": 20837.72224136953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.15471553190326, 391.1472988884235, 398.4580969880962, 399.7062159312903, 416.1012213618583, 18471.722924156147, 20116.69824952623, 20126.295579654696, 20317.288894888683, 20340.99999999998, 19527.316543920966, 19755.111020004097, 20261.0, 20429.387118386472, 20450.18199272031, 5231.607629427789, 6040.124181993598, 6134.478569321613, 6185.0, 6260.092286618437, 6105.0, 6109.602875813058, 6153.876922461548, 6157.138000679908, 6188.350223226541, 5883.970580147094, 5954.732037058343, 6038.154658347835, 6064.0, 6064.3026052003925, 5547.72261386929, 6018.4583387494895, 6059.636421814683, 6122.6632535210765, 6125.019996800513, 5261.421243663194, 5270.314859068335, 5293.894122117536, 5309.469053094698, 5318.30861987943, 25272.73636318195, 25490.0, 25550.59471729054, 25615.0, 26040.609390858965, 2099.5590925905576, 2492.5638013347593, 2591.948161036769, 3034.8482575871117, 3073.5205149312173, 15215.467458638894, 15399.307031183624, 15789.973651712602, 15905.602359940978, 16378.754318685229, 1868.318063906678, 1896.753422055124, 1900.5248687828043, 1952.7851936286997, 1955.07134111296, 112518.20445655064, 118308.40845795802, 118413.0, 118456.99999999914, 118971.0, 11245.437728113726, 11449.828252576051, 11671.533138674527, 11798.646040618794, 11931.522739090513, 14173.645658858612, 14214.715705685829, 14232.003759736719, 14252.287385630885, 14283.071600345944, 8667.626688609922, 8892.8666070009, 8922.464652120894, 8945.15021072997, 8998.910010899937, 9028.503432311287, 9059.456432614064, 9108.453492790453, 9110.498922559324, 9308.30187735909, 6752.628605426748, 6847.246802851682, 6851.965740171367, 6862.9313706862295, 6864.935934930094, 1882.1436246507806, 1908.7900330963582, 1911.6559019376368, 1917.1947781931344, 1919.1555715485142, 922.4695799914941, 1043.1758910460803, 1067.2315932528568, 1068.9144868410508, 1074.2533938912381, 21021.89489052545, 21342.466438338855, 21748.565028699653, 21982.230621928156, 22047.22834700778, 972.7665360313474, 972.9854052189223, 1007.9445630490246, 1021.3667526133845, 1058.5497867920844, 11157.27477713946, 11409.801970793113, 11410.486528106256, 11438.142139339583, 11609.76780464386, 478910.0, 539234.0, 550930.0, 557120.0, 560987.0, 92582.0, 102307.48846255722, 104126.0, 105330.47334763277, 110341.44829275963, 938.9718258506788, 977.5747549815853, 1004.834202356603, 1021.6577446555339, 1022.4018948914968, 14141.858581414053, 18460.538486537946, 19301.710474342613, 19637.214511419385, 19784.505387365432, 50006.74996624994, 51194.48805512046, 51435.228471573704, 52555.2671619078, 52654.73672631612, 71744.64127679328, 73914.89127663197, 74895.62552187205, 75057.24942750503, 75144.0, 7958.482698624803, 8044.557549334842, 8102.797430064295, 8157.8776318354085, 8307.6676932922, 12697.365131743562, 12704.87295127074, 13012.089153759145, 13169.209847409185, 13320.200787952754, 8943.329250306122, 11114.333140011626, 11126.610568630384, 11133.0, 11505.309681419141, 10677.14582833387, 10883.67348979532, 10982.670519884416, 10986.835197471884, 11317.490712917774, 9038.276937845088, 10516.106130978647, 10857.511411986638, 11206.159538034843, 11408.258463199865, 7604.391648667983, 7951.920480795118, 7995.2804247618, 8174.795630109057, 8466.238038576557, 11003.669889903316, 11164.553417863195, 11321.433928303388, 11410.771784564427, 11427.714307142389], "value": 9829.572261744894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.7366710825606, 1525.046845721424, 1737.8262173782616, 1746.4062218845597, 1860.0792607659212, 7750.418718596114, 7940.6823727051, 8188.959055204723, 8665.220130188281, 8677.002144753347, 8164.714235001776, 8444.071152173256, 8460.746177614681, 8556.0, 8559.358048146383, 2341.7072865891764, 2377.607694730376, 2379.7382287948312, 2382.8927698253533, 2391.4260577461373, 2376.1564644551195, 2390.008146619151, 2429.4776623026046, 2430.86630235337, 2431.0, 2372.6796882420913, 2385.42749740062, 2390.8206884483734, 2399.568077746005, 2405.254371144942, 2347.190219374313, 2353.435175557871, 2357.5284943011466, 2380.226426411406, 2384.3800611841048, 2333.8366314358, 2380.9523809523885, 2400.5078958813574, 2401.3156250468705, 2404.074431343945, 8482.236598706084, 8593.914060859437, 8668.52323122228, 8680.782980425476, 8700.390972631918, 5192.92210616841, 5332.093544097513, 5333.973330133364, 5352.4915133062295, 5357.249985002103, 3514.822534450962, 3563.322968635951, 3569.8750543731103, 3607.278544291126, 3632.600413954463, 1018.2108865629123, 1081.551156270147, 1096.4901320885826, 1115.9163062770244, 1123.8651361836633, 11104.666859994213, 11153.553857845594, 11169.553217871275, 11175.441227938652, 11213.943930280378, 4556.407667003335, 4650.767461626972, 4732.597729193058, 4736.928946065812, 4741.5495527924795, 9708.611655533841, 9746.415215087118, 9759.707208783748, 9761.51192440375, 9793.657221997197, 5273.709945953009, 5292.888493416425, 5330.653507521999, 5336.386315573681, 5350.5184533392185, 4385.693001489865, 4426.734395936254, 4811.374521312207, 4837.274408838632, 4863.3434486343895, 3932.803359831997, 3969.0, 4021.235965166609, 4058.2289365021143, 4065.8780236592947, 1286.6526037969695, 1289.1620446709715, 1299.6231092983119, 1301.2387753164317, 1304.0936549098285, 922.7739203894954, 931.1433481197315, 936.9765755856158, 937.2736129499692, 950.7100334398075, 5581.967336042845, 5725.141228815628, 5726.4273572642405, 5884.205632239678, 5954.3152537458745, 896.8968568614563, 916.3036092569695, 919.103873723128, 920.8388532007006, 958.3291695812959, 3835.827387767557, 3920.4903362562686, 3963.365861462152, 3969.4641223435046, 4007.39889016644, 10081.747956301151, 10264.076233139054, 10303.227257955683, 10364.0, 10378.221633377527, 9953.104220620178, 9975.700728978143, 10245.795084098276, 10336.741581460374, 10340.482975851177, 7998.48009879356, 8079.959600201962, 8111.0, 8112.837743245102, 8213.178682131858, 5647.745851436777, 5653.123765816305, 5704.657720536615, 5813.912791308049, 5823.621464604787, 9342.299327550596, 9552.474613896302, 9648.75878103053, 9667.516624168904, 9682.467464289532, 9432.905670943202, 9688.564014619218, 9846.753831154278, 9860.802783944424, 9861.605535778488, 6404.679766011774, 6569.24453687832, 6676.432503237279, 6864.347886950732, 7014.737347277539, 7795.142534321333, 7874.881876771968, 8137.145599711945, 8162.306203972491, 8178.46839955401, 6332.240131184288, 6454.709538070892, 7312.670929808065, 7346.742863999734, 7416.666250018654, 7156.320149585781, 7197.964010180125, 7213.567185968858, 7242.203357630762, 7308.8903666443985, 6644.069830223676, 6671.733130674718, 6729.663516824041, 6731.0, 6745.190577130776, 4261.595148460891, 4450.933236001398, 4860.73265970375, 4904.705717656952, 4947.010597880367, 4816.590589799906, 4838.540338667821, 4859.659823812299, 4989.950100498949, 5045.470225626404], "value": 4522.616634358671, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.8215542053086, 314.4230337330996, 322.5516532020491, 325.70198268584244, 327.1151535097561, 5343.851072019515, 5523.806666766664, 5561.082421400469, 5579.46995035472, 5627.9718601406985, 4270.402143699885, 5255.237990491377, 5489.286392768944, 5507.779688812443, 5533.916991245134, 4013.2776100301935, 4187.748735075906, 4215.0, 4241.575842415749, 4281.978590107045, 3414.8634054637787, 3842.3852183650483, 3851.732394573946, 4229.0, 4280.373458085938, 3284.5894263217097, 3992.40113982902, 4133.772642504661, 4210.831566737342, 4219.641330486897, 4240.197915885737, 4244.936325955113, 4246.511651160125, 4259.190753756776, 4278.208531421681, 3565.5721313442295, 3673.9081522961874, 3677.2829298286847, 3685.7788532688123, 3725.9194833498254, 5345.358556973188, 5391.245225668409, 5393.649412788156, 5445.401005889349, 5457.727113644302, 4090.6318431341324, 4903.975480122613, 4925.950740492585, 4944.873704307147, 4972.0, 4778.95221047792, 5413.566914646858, 5600.411956744524, 5863.179154918314, 5881.705914704247, 4093.406456063884, 4104.240715467618, 4119.299719047769, 4126.731762435462, 4134.441850350225, 6071.446415188609, 6150.630962142286, 6157.2919114301985, 6161.7535298588, 6175.240056583852, 4020.5979402060143, 4121.649659778953, 4161.0, 4182.895427614334, 4193.622573968358, 3706.2772759312215, 3742.8689995850013, 4161.479815023122, 4244.72409293395, 4254.191703576311, 4293.141371725605, 4449.7997590108525, 4703.811847526129, 4895.73073480962, 4915.410150781929, 5207.989847817235, 5535.916961245584, 5813.0, 5906.350301466835, 5947.899638566879, 1282.3267784413258, 1339.2968691437075, 1373.5604606526015, 1378.5726424808342, 1387.4172847404116, 817.0807841178674, 919.8574220995756, 940.2571968145226, 959.2757468111671, 965.9372140810825, 4500.549945005539, 5086.0, 5247.084814042847, 5269.285153966444, 5320.893582128336, 5239.973800130975, 5415.998040362546, 5741.741621627037, 5753.539716822645, 5759.0, 5053.84838454847, 5115.948840511547, 5258.053550360895, 5276.788928442896, 5637.548996080303, 2601.6877974642784, 2930.1100789990764, 2930.457865294927, 3031.378567393679, 3064.2492589315316, 4906.0433215522635, 5734.5125664319, 5751.309842818889, 5755.776765011607, 6019.488343490765, 5130.51260130287, 5481.561475081984, 5521.834344969658, 5832.708364581754, 5912.822615321547, 5569.832905012856, 5999.340072592098, 6008.879822403527, 6029.306629737549, 6040.0, 5358.812441564526, 5370.301860758076, 5776.508996735157, 5817.680027598522, 5824.5631577631, 5076.949230507647, 5262.526612492123, 5285.0, 5538.695371754592, 5713.400092990176, 5316.202569614588, 5425.620206585501, 5918.254115036161, 5988.520918326436, 6033.637981721111, 3253.5282384054653, 3557.875474358385, 3701.4817925489915, 3856.479375284413, 3870.83874837542, 5876.294844618673, 6049.334573197033, 6073.726682299395, 6076.51387888959, 6105.45050945417, 4960.305557222064, 5899.46904778572, 5907.172995780508, 5911.0, 5929.835610002405, 5644.520215781705, 5734.655920644775, 5919.625813286558, 5922.73347699346, 5944.197533332945, 5494.81861399801, 5604.859878502907, 5733.799317023884, 5753.598497149767, 5755.079187330007, 5864.413558644102, 5926.0, 5961.52307815365, 5986.580939334205, 6000.009998350398, 4283.957160428356, 4754.31062495943, 5120.590352771695, 5743.798967036134, 5768.596198266081], "value": 4219.902268526422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.3454807000719, 778.0313509680449, 788.1409263902345, 811.4847072109211, 816.6978218059319, 12723.872761272372, 14585.34365953532, 15186.620334491643, 15401.768973465407, 15403.614909627266, 12694.873051269484, 13156.289682341283, 13807.723845523084, 13954.651133721662, 14045.50840720575, 853.138330286412, 853.2875049333813, 884.6594061286387, 891.7101941868887, 895.3687650206622, 868.4138206710477, 886.3706768194589, 896.2292428511507, 899.7570655922897, 902.7111324376202, 922.8800255966748, 931.1293940165966, 939.0327962198946, 939.9765005874874, 941.3457646935383, 931.4504442379028, 932.7668082979255, 932.9067093290616, 934.5794392523392, 937.0863408177041, 684.9178098628197, 688.3667026335783, 693.628908533937, 701.6632016632045, 702.8769965256059, 14426.783598246035, 14488.34802433893, 14729.337179826827, 14795.630109247248, 14834.554963351116, 12678.429470673842, 13654.0, 14036.298185090807, 14235.145891246559, 14314.64213394663, 11773.882261177365, 11947.641570752892, 12580.748385032248, 12955.09314347996, 13038.348082595832, 4558.8632341029825, 4621.306803979396, 4652.278896771006, 4678.929816052762, 4785.904281914377, 94970.0, 96361.5545766814, 96918.51540742321, 99974.50012749889, 100404.0, 9452.95273523628, 10553.947230263799, 10864.619738309122, 10888.891111088946, 10894.291871028357, 19874.801251987294, 19914.701279480818, 20052.498687532636, 20153.99230038492, 20327.8983605084, 7761.495502792301, 7779.805504862424, 7836.804079898048, 7905.960470197612, 8005.599720014093, 3703.2778608171147, 4339.5660433956355, 4441.755703436277, 4476.955230447719, 4498.415206023196, 3035.134986528873, 3341.565596472443, 3414.5219669246326, 3419.2135808764124, 3452.9654703453148, 537.9946200538022, 652.1977967100398, 656.4518626946553, 657.9046038324512, 666.8862998792029, 574.5001848391926, 593.1606776411329, 619.6406084470996, 632.5761739634456, 649.0912722188879, 8460.238578527962, 8508.617112229964, 8932.883389576304, 9103.0, 9723.756906077264, 636.3032479435011, 656.8095252376853, 657.9769708060195, 671.5198632977445, 716.9032180655649, 3931.488906442144, 4034.829899329162, 4230.978845105755, 4317.9136417271475, 4493.910121797546, 70560.0, 100344.49827750814, 107227.0, 109566.0, 114593.4270328643, 71660.0, 90981.09018909858, 99174.51238231432, 103856.48071759593, 104752.0, 79796.0, 80582.59708701419, 87026.0, 90371.19257614811, 90496.0, 9376.0, 9702.951485242811, 9925.0, 9928.354656947275, 9941.701748947544, 44022.339664905696, 52275.73862130665, 53035.20447193372, 56950.0, 58437.662493501484, 58116.70941645265, 71296.28703712896, 72493.0, 86207.0, 87570.0, 10576.418296993432, 12901.290429026118, 12952.0, 13239.735205295647, 13270.81288213634, 14417.0, 14612.561623151323, 14742.48401305992, 14782.260886955824, 14931.477398291008, 13548.932255338661, 14001.369938353002, 14012.859871401155, 14824.925875370554, 14947.476838310607, 13263.933680331536, 13656.317184140555, 13775.0, 13930.303484825921, 14507.347169377614, 12970.93514532459, 13053.738925221252, 13260.602181934557, 13330.0, 13383.130096544082, 10814.728906929318, 10977.506012229629, 11315.886841131483, 11700.941495292469, 11715.472803723682, 11426.0, 11575.479103440197, 11783.0, 11785.116116291141, 11810.0], "value": 7540.324568013567, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 129.0, 175.0, 164.0, 116.0, 123.0, 105.0, 175.0], "value": 132.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 147.0, 133.0, 140.0, 141.0, 111.0, 111.0, 134.0, 111.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 197.0, 198.0, 196.0, 194.0, 192.0, 195.0, 197.0, 196.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 60.0, 60.0, 73.0, 75.0, 54.0, 52.0, 62.0, 106.0, 83.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 88.0, 73.0, 70.0, 73.0, 86.0, 69.0, 74.0, 88.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 130.0, 94.0, 75.0, 137.0, 133.0, 89.0, 87.0, 89.0, 99.0], "value": 102.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 99.0, 100.0, 97.0, 97.0, 100.0, 99.0, 106.0], "value": 99.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 86.0, 87.0, 102.0, 72.0, 147.0, 148.0, 94.0, 143.0], "value": 106.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 57.0, 59.0, 62.0, 91.0, 92.0, 86.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 67.0, 82.0, 81.0, 112.0, 102.0, 103.0, 75.0, 69.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 82.0, 82.0, 83.0, 84.0, 84.0, 82.0, 85.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 135.0, 142.0, 158.0, 148.0, 149.0, 165.0, 185.0, 139.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 238.0, 166.0, 162.0, 176.0, 235.0, 168.0, 163.0, 209.0, 221.0], "value": 193.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 65.0, 68.0, 66.0, 77.0, 79.0, 87.0, 85.0], "value": 72.8, "unit": "score"}]}]} 21:01:28 INFO - Return code: 0 21:01:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:01:28 ERROR - # TBPL SUCCESS # 21:01:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:01:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:01:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:01:28 INFO - 2017-02-07 21:01:28.857 screenresolution[1660:10416] starting screenresolution argv=screenresolution get 21:01:28 INFO - 2017-02-07 21:01:28.860 screenresolution[1660:10416] Display 0: 1600x1200x32@60 21:01:28 INFO - 2017-02-07 21:01:28.867 screenresolution[1661:10420] starting screenresolution argv=screenresolution list 21:01:28 INFO - Available Modes on Display 0 21:01:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:01:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:01:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:01:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:01:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:01:29 INFO - Intel Iris: 21:01:29 INFO - Chipset Model: Intel Iris 21:01:29 INFO - Type: GPU 21:01:29 INFO - Bus: Built-In 21:01:29 INFO - VRAM (Dynamic, Max): 1536 MB 21:01:29 INFO - Vendor: Intel (0x8086) 21:01:29 INFO - Device ID: 0x0a2e 21:01:29 INFO - Revision ID: 0x0009 21:01:29 INFO - Displays: 21:01:29 INFO - Display: 21:01:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:01:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:01:29 INFO - Display Serial Number: 1600x1200 60 21:01:29 INFO - Main Display: Yes 21:01:29 INFO - Mirror: Off 21:01:29 INFO - Online: Yes 21:01:29 INFO - Rotation: Supported 21:01:29 INFO - Return code: 0 21:01:29 INFO - Running post-action listener: _resource_record_post_action 21:01:29 INFO - [mozharness: 2017-02-08 05:01:29.108123Z] Finished run-tests step (success) 21:01:29 INFO - Running post-run listener: _resource_record_post_run 21:01:29 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40265728; Write bytes: 502200320; Read time: 387; Write time: 1631 21:01:29 INFO - TinderboxPrint: CPU usage
54.3% 21:01:29 INFO - TinderboxPrint: I/O read bytes / time
40,265,728 / 387 21:01:29 INFO - TinderboxPrint: I/O write bytes / time
502,200,320 / 1,631 21:01:29 INFO - TinderboxPrint: CPU idle
2,190.2 (45.7%) 21:01:29 INFO - TinderboxPrint: CPU system
967.1 (20.2%) 21:01:29 INFO - TinderboxPrint: CPU user
1,632.4 (34.1%) 21:01:29 INFO - TinderboxPrint: Swap in / out
231,940,096 / 0 21:01:29 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 187595264; Write bytes: 186163200; Read time: 16843; Write time: 340 21:01:29 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 38291456; Write bytes: 309082112; Read time: 367; Write time: 1265 21:01:29 INFO - Running post-run listener: _upload_blobber_files 21:01:29 INFO - Blob upload gear active. 21:01:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:01:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:01:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:01:30 INFO - (blobuploader) - INFO - Open directory for files ... 21:01:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:01:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:01:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:01:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:01:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:01:30 INFO - (blobuploader) - INFO - Done attempting. 21:01:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:01:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:01:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:01:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:01:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:01:31 INFO - (blobuploader) - INFO - Done attempting. 21:01:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:01:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:01:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:01:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:01:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:01:34 INFO - (blobuploader) - INFO - Done attempting. 21:01:34 INFO - (blobuploader) - INFO - Iteration through files over. 21:01:34 INFO - Return code: 0 21:01:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:01:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:01:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e407dfcd72ed8b1d574e21745ad150bfa6f6cf44b0bd978e9c3e2aa05177d1d455667632ebc84265e2bde414b9778fc9ff4a7c40ddceb540077e797a66db129", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dccb877c12db7cf81fb286ba6cd7db01729871ccae8a105749519b9e59f672283ff8d7c4a2be43779747e92ee253ec845770517afbbd70cd4064bc67e6db60cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7468b4e911773473d27bf7118606c30ec81a9798d0f8b14f19d1c363297a826a4e37c27a7d622915912003eddb0b71e9bc97d2ae2448fab7646f5bcac67e7035"} 21:01:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:01:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:01:34 INFO - Contents: 21:01:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e407dfcd72ed8b1d574e21745ad150bfa6f6cf44b0bd978e9c3e2aa05177d1d455667632ebc84265e2bde414b9778fc9ff4a7c40ddceb540077e797a66db129", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dccb877c12db7cf81fb286ba6cd7db01729871ccae8a105749519b9e59f672283ff8d7c4a2be43779747e92ee253ec845770517afbbd70cd4064bc67e6db60cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7468b4e911773473d27bf7118606c30ec81a9798d0f8b14f19d1c363297a826a4e37c27a7d622915912003eddb0b71e9bc97d2ae2448fab7646f5bcac67e7035"} 21:01:34 INFO - Running post-run listener: copy_logs_to_upload_dir 21:01:34 INFO - Copying logs to upload dir... 21:01:34 INFO - mkdir: /builds/slave/test/build/upload/logs 21:01:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.900138 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-07 21:01:34.260113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 21:01:34.267561) ========= 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.xKPbJRqtB2/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.85JPBzXxu4/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/Mozilla-Beta/sha512/0e407dfcd72ed8b1d574e21745ad150bfa6f6cf44b0bd978e9c3e2aa05177d1d455667632ebc84265e2bde414b9778fc9ff4a7c40ddceb540077e797a66db129", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dccb877c12db7cf81fb286ba6cd7db01729871ccae8a105749519b9e59f672283ff8d7c4a2be43779747e92ee253ec845770517afbbd70cd4064bc67e6db60cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7468b4e911773473d27bf7118606c30ec81a9798d0f8b14f19d1c363297a826a4e37c27a7d622915912003eddb0b71e9bc97d2ae2448fab7646f5bcac67e7035"} build_url:https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011097 build_url: 'https://queue.taskcluster.net/v1/task/HZ0AGj98RUmqpTJkuXIiRQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e407dfcd72ed8b1d574e21745ad150bfa6f6cf44b0bd978e9c3e2aa05177d1d455667632ebc84265e2bde414b9778fc9ff4a7c40ddceb540077e797a66db129", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dccb877c12db7cf81fb286ba6cd7db01729871ccae8a105749519b9e59f672283ff8d7c4a2be43779747e92ee253ec845770517afbbd70cd4064bc67e6db60cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7468b4e911773473d27bf7118606c30ec81a9798d0f8b14f19d1c363297a826a4e37c27a7d622915912003eddb0b71e9bc97d2ae2448fab7646f5bcac67e7035"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 21:01:34.301128) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 21:01:34.304480) ========= 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.xKPbJRqtB2/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.85JPBzXxu4/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005193 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 21:01:34.352727) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 21:01:34.353013) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 21:01:34.356304) ========= ========= Total master_lag: 3.49 =========