builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1494895448.08 results: success (0) revision: 3e166b6838931b3933ca274331f9e0e115af5cc0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.083322) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.083777) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.084056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.107911) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.108214) ========= 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.ffvuOrkNvL/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.od8xTFy1pV/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-05-15 17:44:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.1M=0.001s 2017-05-15 17:44:08 (13.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123578 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.254098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.254427) ========= 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.ffvuOrkNvL/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.od8xTFy1pV/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.021481 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:08.303138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 17:44:08.303481) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3e166b6838931b3933ca274331f9e0e115af5cc0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 3e166b6838931b3933ca274331f9e0e115af5cc0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffvuOrkNvL/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.od8xTFy1pV/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-05-15 17:44:08,388 truncating revision to first 12 chars 2017-05-15 17:44:08,388 Setting DEBUG logging. 2017-05-15 17:44:08,389 attempt 1/10 2017-05-15 17:44:08,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3e166b683893?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 17:44:09,800 unpacking tar archive at: mozilla-central-3e166b683893/testing/mozharness/ program finished with exit code 0 elapsedTime=1.683137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 17:44:10.008803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:10.009131) ========= script_repo_revision: 3e166b6838931b3933ca274331f9e0e115af5cc0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:10.009531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:10.009806) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 17:44:10.028572) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-15 17:44:10.028852) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffvuOrkNvL/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.od8xTFy1pV/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:44:10 INFO - MultiFileLogger online at 20170515 17:44:10 in /builds/slave/test 17:44:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:44:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:44:10 INFO - {'append_to_log': False, 17:44:10 INFO - 'base_work_dir': '/builds/slave/test', 17:44:10 INFO - 'blob_upload_branch': 'Firefox', 17:44:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:44:10 INFO - 'branch': 'Firefox', 17:44:10 INFO - 'buildbot_json_path': 'buildprops.json', 17:44:10 INFO - 'config_files': ('talos/mac_config.py',), 17:44:10 INFO - 'default_actions': ('clobber', 17:44:10 INFO - 'read-buildbot-config', 17:44:10 INFO - 'download-and-extract', 17:44:10 INFO - 'populate-webroot', 17:44:10 INFO - 'create-virtualenv', 17:44:10 INFO - 'install', 17:44:10 INFO - 'run-tests'), 17:44:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:44:10 INFO - 'download_minidump_stackwalk': True, 17:44:10 INFO - 'download_symbols': 'ondemand', 17:44:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:44:10 INFO - 'tooltool.py': '/tools/tooltool.py', 17:44:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:44:10 INFO - '/tools/misc-python/virtualenv.py')}, 17:44:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:44:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:44:10 INFO - 'gecko_profile': False, 17:44:10 INFO - 'gecko_profile_interval': 0, 17:44:10 INFO - 'installer_path': 'installer.exe', 17:44:10 INFO - 'log_level': 'info', 17:44:10 INFO - 'log_name': 'talos', 17:44:10 INFO - 'log_to_console': True, 17:44:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:44:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:44:10 INFO - 'opt_config_files': (), 17:44:10 INFO - 'pip_index': False, 17:44:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:44:10 INFO - 'cmd': ('bash', 17:44:10 INFO - '-c', 17:44:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:44:10 INFO - 'enabled': True, 17:44:10 INFO - 'halt_on_failure': False, 17:44:10 INFO - 'name': 'check_screen_resolution'},), 17:44:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:44:10 INFO - 'cmd': ('bash', 17:44:10 INFO - '-c', 17:44:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:44:10 INFO - 'enabled': True, 17:44:10 INFO - 'halt_on_failure': False, 17:44:10 INFO - 'name': 'check_screen_resolution'},), 17:44:10 INFO - 'run_cmd_checks_enabled': True, 17:44:10 INFO - 'suite': 'dromaeojs-e10s', 17:44:10 INFO - 'system_bits': '32', 17:44:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:44:10 INFO - 'title': 't-yosemite-r7-0342', 17:44:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:44:10 INFO - 'use_talos_json': True, 17:44:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:44:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:44:10 INFO - 'work_dir': 'build'} 17:44:10 INFO - [mozharness: 2017-05-16 00:44:10.235043Z] Running clobber step. 17:44:10 INFO - Running pre-action listener: _resource_record_pre_action 17:44:10 INFO - Running main action method: clobber 17:44:10 INFO - rmtree: /builds/slave/test/build 17:44:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:44:12 INFO - Running post-action listener: _resource_record_post_action 17:44:12 INFO - [mozharness: 2017-05-16 00:44:12.131950Z] Finished clobber step (success) 17:44:12 INFO - [mozharness: 2017-05-16 00:44:12.132092Z] Running read-buildbot-config step. 17:44:12 INFO - Running pre-action listener: _resource_record_pre_action 17:44:12 INFO - Running main action method: read_buildbot_config 17:44:12 INFO - Using buildbot properties: 17:44:12 INFO - { 17:44:12 INFO - "project": "", 17:44:12 INFO - "product": "firefox", 17:44:12 INFO - "who": "kwierso@gmail.com", 17:44:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:44:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:44:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:44:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:44:12 INFO - "stage_platform": "macosx64", 17:44:12 INFO - "basedir": "/builds/slave/test", 17:44:12 INFO - "buildnumber": 73, 17:44:12 INFO - "platform": "yosemite_r7", 17:44:12 INFO - "parent_task_id": "G2y7wgxYSROQkCgHHi-C4Q", 17:44:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:44:12 INFO - "slavebuilddir": "test", 17:44:12 INFO - "taskId": "NniKyjGbSoiV5ohdMuaCVw", 17:44:12 INFO - "branch": "mozilla-central", 17:44:12 INFO - "script_repo_revision": "production", 17:44:12 INFO - "revision": "3e166b6838931b3933ca274331f9e0e115af5cc0", 17:44:12 INFO - "slavename": "t-yosemite-r7-0342", 17:44:12 INFO - "repo_path": "mozilla-central" 17:44:12 INFO - } 17:44:12 INFO - Finding installer, test and symbols from parent task. 17:44:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NniKyjGbSoiV5ohdMuaCVw'}, attempt #1 17:44:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NniKyjGbSoiV5ohdMuaCVw'}, attempt #1 17:44:13 INFO - Task dependencies: AjyrtusdSnCNl84V6FkIpg 17:44:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q'}, attempt #1 17:44:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:44:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/test_packages.json 17:44:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/target.crashreporter-symbols.zip 17:44:13 INFO - Running post-action listener: _resource_record_post_action 17:44:13 INFO - [mozharness: 2017-05-16 00:44:13.957429Z] Finished read-buildbot-config step (success) 17:44:13 INFO - [mozharness: 2017-05-16 00:44:13.957549Z] Running download-and-extract step. 17:44:13 INFO - Running pre-action listener: _resource_record_pre_action 17:44:13 INFO - Running main action method: download_and_extract 17:44:13 INFO - mkdir: /builds/slave/test/build/tests 17:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:44:13 INFO - trying https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:44:13 INFO - Downloading https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:44:14 INFO - Downloaded 1325 bytes. 17:44:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:44:14 INFO - Using the following test package requirements: 17:44:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:44:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:44:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:44:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:44:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'jsshell-mac.zip'], 17:44:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:44:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:44:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:44:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:44:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:44:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:44:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:44:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:44:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:44:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:44:14 INFO - Fetch https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:44:16 INFO - Content-Length response header: 56565948 17:44:16 INFO - Bytes received: 56565948 17:44:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:44:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:44:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:44:34 INFO - Fetch https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:44:36 INFO - Content-Length response header: 56565948 17:44:36 INFO - Bytes received: 56565948 17:44:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:44:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:44:53 INFO - Fetch https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:44:54 INFO - Content-Length response header: 14410013 17:44:54 INFO - Bytes received: 14410013 17:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:44:54 INFO - trying https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:44:54 INFO - Downloading https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:44:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:44:56 INFO - Downloaded 75956462 bytes. 17:44:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:44:56 INFO - mkdir: /builds/slave/test/properties 17:44:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:44:56 INFO - Writing to file /builds/slave/test/properties/build_url 17:44:56 INFO - Contents: 17:44:56 INFO - build_url:https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:44:56 INFO - Running post-action listener: _resource_record_post_action 17:44:56 INFO - Running post-action listener: set_extra_try_arguments 17:44:56 INFO - [mozharness: 2017-05-16 00:44:56.994569Z] Finished download-and-extract step (success) 17:44:56 INFO - [mozharness: 2017-05-16 00:44:56.994730Z] Running populate-webroot step. 17:44:56 INFO - Running pre-action listener: _resource_record_pre_action 17:44:56 INFO - Running main action method: populate_webroot 17:44:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:44:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:44:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:44:57 INFO - '--tppagecycles', 17:44:57 INFO - '7'], 17:44:57 INFO - 'tests': ['tsvgm']}}, 17:44:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:44:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:44:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:44:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:44:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['damp', 'tps']}, 17:44:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'tests': ['damp', 'tps']}, 17:44:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['dromaeo_dom']}, 17:44:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:44:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:44:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:44:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['a11yr', 17:44:57 INFO - 'ts_paint', 17:44:57 INFO - 'tpaint', 17:44:57 INFO - 'sessionrestore', 17:44:57 INFO - 'sessionrestore_no_auto_restore', 17:44:57 INFO - 'tabpaint']}, 17:44:57 INFO - 'other-e10s': {'tests': ['a11yr', 17:44:57 INFO - 'ts_paint', 17:44:57 INFO - 'tpaint', 17:44:57 INFO - 'sessionrestore', 17:44:57 INFO - 'sessionrestore_no_auto_restore', 17:44:57 INFO - 'tabpaint']}, 17:44:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:44:57 INFO - 'ts_paint', 17:44:57 INFO - 'tpaint', 17:44:57 INFO - 'sessionrestore', 17:44:57 INFO - 'sessionrestore_no_auto_restore', 17:44:57 INFO - 'tabpaint']}, 17:44:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:44:57 INFO - 'ts_paint', 17:44:57 INFO - 'tpaint', 17:44:57 INFO - 'sessionrestore', 17:44:57 INFO - 'sessionrestore_no_auto_restore', 17:44:57 INFO - 'tabpaint']}, 17:44:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['a11yr', 17:44:57 INFO - 'ts_paint', 17:44:57 INFO - 'tpaint', 17:44:57 INFO - 'sessionrestore', 17:44:57 INFO - 'sessionrestore_no_auto_restore', 17:44:57 INFO - 'tabpaint']}, 17:44:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['a11yr', 17:44:57 INFO - 'ts_paint', 17:44:57 INFO - 'tpaint', 17:44:57 INFO - 'sessionrestore', 17:44:57 INFO - 'sessionrestore_no_auto_restore', 17:44:57 INFO - 'tabpaint']}, 17:44:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:44:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:44:57 INFO - 'bloom_basic_ref']}, 17:44:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['tsvgx', 17:44:57 INFO - 'tsvgr_opacity', 17:44:57 INFO - 'tart', 17:44:57 INFO - 'tscrollx', 17:44:57 INFO - 'cart', 17:44:57 INFO - 'tsvg_static']}, 17:44:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:44:57 INFO - 'tsvgr_opacity', 17:44:57 INFO - 'tart', 17:44:57 INFO - 'tscrollx', 17:44:57 INFO - 'cart', 17:44:57 INFO - 'tsvg_static']}, 17:44:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'talos_options': ['--disable-e10s'], 17:44:57 INFO - 'tests': ['tp5o']}, 17:44:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:44:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'talos_options': ['--disable-e10s', 17:44:57 INFO - '--xperf_path', 17:44:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:57 INFO - 'tests': ['tp5n']}, 17:44:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:44:57 INFO - 'talos_options': ['--xperf_path', 17:44:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:57 INFO - 'tests': ['tp5n']}}, 17:44:57 INFO - 'talos.zip': {'path': '', 17:44:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:44:57 INFO - Running post-action listener: _resource_record_post_action 17:44:57 INFO - [mozharness: 2017-05-16 00:44:57.015374Z] Finished populate-webroot step (success) 17:44:57 INFO - [mozharness: 2017-05-16 00:44:57.015512Z] Running create-virtualenv step. 17:44:57 INFO - Running pre-action listener: _resource_record_pre_action 17:44:57 INFO - Running main action method: create_virtualenv 17:44:57 INFO - Creating virtualenv /builds/slave/test/build/venv 17:44:57 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 17:44:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:44:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:44:57 INFO - Using real prefix '/tools/python27' 17:44:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:44:57 INFO - Installing distribute.............................................................................................................................................................................................done. 17:45:00 INFO - Installing pip.................done. 17:45:00 INFO - Return code: 0 17:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:45:01 INFO - Reading from file tmpfile_stdout 17:45:01 INFO - Output received: 17:45:01 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) 17:45:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:01 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:01 INFO - 'HOME': '/Users/cltbld', 17:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:01 INFO - 'LOGNAME': 'cltbld', 17:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:01 INFO - 'MOZ_NO_REMOTE': '1', 17:45:01 INFO - 'NO_EM_RESTART': '1', 17:45:01 INFO - 'PAGER': '/bin/cat', 17:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:01 INFO - 'PWD': '/builds/slave/test', 17:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:01 INFO - 'SHELL': '/bin/bash', 17:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:01 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:01 INFO - 'USER': 'cltbld', 17:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:01 INFO - 'XPC_FLAGS': '0x0', 17:45:01 INFO - 'XPC_SERVICE_NAME': '0', 17:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:01 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 17:45:01 INFO - Cleaning up... 17:45:01 INFO - Return code: 0 17:45:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:01 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:01 INFO - 'HOME': '/Users/cltbld', 17:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:01 INFO - 'LOGNAME': 'cltbld', 17:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:01 INFO - 'MOZ_NO_REMOTE': '1', 17:45:01 INFO - 'NO_EM_RESTART': '1', 17:45:01 INFO - 'PAGER': '/bin/cat', 17:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:01 INFO - 'PWD': '/builds/slave/test', 17:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:01 INFO - 'SHELL': '/bin/bash', 17:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:01 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:01 INFO - 'USER': 'cltbld', 17:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:01 INFO - 'XPC_FLAGS': '0x0', 17:45:01 INFO - 'XPC_SERVICE_NAME': '0', 17:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:01 INFO - Downloading/unpacking psutil>=3.1.1 17:45:01 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 17:45:01 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 17:45:01 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 17:45:01 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 17:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:45:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:45:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:45:03 INFO - Installing collected packages: psutil 17:45:03 INFO - Running setup.py install for psutil 17:45:03 INFO - building 'psutil._psutil_osx' extension 17:45:03 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 17:45:03 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 17:45:03 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 17:45:03 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 17:45:03 INFO - building 'psutil._psutil_posix' extension 17:45:03 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 17:45:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:45:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:45:04 INFO - ^ 17:45:04 INFO - 1 warning generated. 17:45:04 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 17:45:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:45:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:45:04 INFO - Successfully installed psutil 17:45:04 INFO - Cleaning up... 17:45:04 INFO - Return code: 0 17:45:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:04 INFO - 'HOME': '/Users/cltbld', 17:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:04 INFO - 'LOGNAME': 'cltbld', 17:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:04 INFO - 'MOZ_NO_REMOTE': '1', 17:45:04 INFO - 'NO_EM_RESTART': '1', 17:45:04 INFO - 'PAGER': '/bin/cat', 17:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:04 INFO - 'PWD': '/builds/slave/test', 17:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:04 INFO - 'SHELL': '/bin/bash', 17:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:04 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:04 INFO - 'USER': 'cltbld', 17:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:04 INFO - 'XPC_FLAGS': '0x0', 17:45:04 INFO - 'XPC_SERVICE_NAME': '0', 17:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:45:04 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 17:45:04 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 17:45:04 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 17:45:04 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 17:45:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:45:06 INFO - Installing collected packages: mozsystemmonitor 17:45:06 INFO - Running setup.py install for mozsystemmonitor 17:45:06 INFO - Successfully installed mozsystemmonitor 17:45:06 INFO - Cleaning up... 17:45:06 INFO - Return code: 0 17:45:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:06 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:06 INFO - 'HOME': '/Users/cltbld', 17:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:06 INFO - 'LOGNAME': 'cltbld', 17:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:06 INFO - 'MOZ_NO_REMOTE': '1', 17:45:06 INFO - 'NO_EM_RESTART': '1', 17:45:06 INFO - 'PAGER': '/bin/cat', 17:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:06 INFO - 'PWD': '/builds/slave/test', 17:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:06 INFO - 'SHELL': '/bin/bash', 17:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:06 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:06 INFO - 'USER': 'cltbld', 17:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:06 INFO - 'XPC_FLAGS': '0x0', 17:45:06 INFO - 'XPC_SERVICE_NAME': '0', 17:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:06 INFO - Downloading/unpacking jsonschema==2.5.1 17:45:06 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 17:45:06 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 17:45:06 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 17:45:06 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 17:45:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:45:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:45:08 INFO - warning: no files found matching '*.txt' 17:45:08 INFO - no previously-included directories found matching 'build' 17:45:08 INFO - no previously-included directories found matching 'dist' 17:45:08 INFO - no previously-included directories found matching '.git*' 17:45:08 INFO - Installing collected packages: jsonschema, functools32 17:45:08 INFO - Running setup.py install for functools32 17:45:08 INFO - warning: no files found matching '*.txt' 17:45:08 INFO - no previously-included directories found matching 'build' 17:45:08 INFO - no previously-included directories found matching 'dist' 17:45:08 INFO - no previously-included directories found matching '.git*' 17:45:08 INFO - Successfully installed jsonschema functools32 17:45:08 INFO - Cleaning up... 17:45:08 INFO - Return code: 0 17:45:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:08 INFO - 'HOME': '/Users/cltbld', 17:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:08 INFO - 'LOGNAME': 'cltbld', 17:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:08 INFO - 'MOZ_NO_REMOTE': '1', 17:45:08 INFO - 'NO_EM_RESTART': '1', 17:45:08 INFO - 'PAGER': '/bin/cat', 17:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:08 INFO - 'PWD': '/builds/slave/test', 17:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:08 INFO - 'SHELL': '/bin/bash', 17:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:08 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:08 INFO - 'USER': 'cltbld', 17:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:08 INFO - 'XPC_FLAGS': '0x0', 17:45:08 INFO - 'XPC_SERVICE_NAME': '0', 17:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:45:09 INFO - Cleaning up... 17:45:09 INFO - Return code: 0 17:45:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:09 INFO - 'HOME': '/Users/cltbld', 17:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:09 INFO - 'LOGNAME': 'cltbld', 17:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:09 INFO - 'MOZ_NO_REMOTE': '1', 17:45:09 INFO - 'NO_EM_RESTART': '1', 17:45:09 INFO - 'PAGER': '/bin/cat', 17:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:09 INFO - 'PWD': '/builds/slave/test', 17:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:09 INFO - 'SHELL': '/bin/bash', 17:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:09 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:09 INFO - 'USER': 'cltbld', 17:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:09 INFO - 'XPC_FLAGS': '0x0', 17:45:09 INFO - 'XPC_SERVICE_NAME': '0', 17:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:09 INFO - Downloading/unpacking blobuploader==1.2.4 17:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:10 INFO - Downloading blobuploader-1.2.4.tar.gz 17:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:45:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:45: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 17:45: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 17:45: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 17:45: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 17:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:45:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:11 INFO - Downloading docopt-0.6.1.tar.gz 17:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:45:11 INFO - Installing collected packages: blobuploader, requests, docopt 17:45:11 INFO - Running setup.py install for blobuploader 17:45:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:45:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:45:11 INFO - Running setup.py install for requests 17:45:12 INFO - Running setup.py install for docopt 17:45:12 INFO - Successfully installed blobuploader requests docopt 17:45:12 INFO - Cleaning up... 17:45:12 INFO - Return code: 0 17:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:12 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:12 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:45:12 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:12 INFO - 'HOME': '/Users/cltbld', 17:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:12 INFO - 'LOGNAME': 'cltbld', 17:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:12 INFO - 'MOZ_NO_REMOTE': '1', 17:45:12 INFO - 'NO_EM_RESTART': '1', 17:45:12 INFO - 'PAGER': '/bin/cat', 17:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:12 INFO - 'PWD': '/builds/slave/test', 17:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:12 INFO - 'SHELL': '/bin/bash', 17:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:12 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:12 INFO - 'USER': 'cltbld', 17:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:12 INFO - 'XPC_FLAGS': '0x0', 17:45:12 INFO - 'XPC_SERVICE_NAME': '0', 17:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:45:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fjWPj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:45:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-U4_hDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9sH4DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SpOls7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-btvQoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-z0Tsov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IzzuIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-M7EvBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Uk5gmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-rXX6aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:45:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-FEw9kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:45:14 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Map9zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:45:14 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9W03GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:45:14 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_z9m7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:14 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ggS8lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:45:14 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uzF7lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:45:14 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-X4biIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:45:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:45:14 INFO - Running setup.py install for manifestparser 17:45:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:45:14 INFO - Running setup.py install for mozcrash 17:45:14 INFO - Running setup.py install for mozdebug 17:45:15 INFO - Running setup.py install for mozdevice 17:45:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Running setup.py install for mozfile 17:45:15 INFO - Running setup.py install for mozhttpd 17:45:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Running setup.py install for mozinfo 17:45:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Running setup.py install for mozInstall 17:45:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:45:15 INFO - Running setup.py install for mozleak 17:45:15 INFO - Running setup.py install for mozlog 17:45:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:45:16 INFO - Running setup.py install for moznetwork 17:45:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:45:16 INFO - Running setup.py install for mozprocess 17:45:16 INFO - Running setup.py install for mozprofile 17:45:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:45:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:45:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:45:16 INFO - Running setup.py install for mozrunner 17:45:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:45:16 INFO - Running setup.py install for mozscreenshot 17:45:16 INFO - Running setup.py install for moztest 17:45:17 INFO - Running setup.py install for mozversion 17:45:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:45:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:45:17 INFO - Cleaning up... 17:45:17 INFO - Return code: 0 17:45:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:17 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:17 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:45:17 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:45:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:17 INFO - 'HOME': '/Users/cltbld', 17:45:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:17 INFO - 'LOGNAME': 'cltbld', 17:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:17 INFO - 'MOZ_NO_REMOTE': '1', 17:45:17 INFO - 'NO_EM_RESTART': '1', 17:45:17 INFO - 'PAGER': '/bin/cat', 17:45:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:17 INFO - 'PWD': '/builds/slave/test', 17:45:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:17 INFO - 'SHELL': '/bin/bash', 17:45:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:17 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:17 INFO - 'USER': 'cltbld', 17:45:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:17 INFO - 'XPC_FLAGS': '0x0', 17:45:17 INFO - 'XPC_SERVICE_NAME': '0', 17:45:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:45:17 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7K2iuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:45:17 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)) 17:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:45:17 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-MMytQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:45:17 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)) 17:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:45:17 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3dtOPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:45:17 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)) 17:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:45:17 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-8_UV7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 17:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:45:17 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-XpL1ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:45:17 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)) 17:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:45:17 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-meC2Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2PU2tz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-oaBPlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-dD4RsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-yE1_aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-geqFrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_eEPlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-npdNum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-dEQOa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:45:18 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)) 17:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:18 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-MUr8_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:19 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)) 17:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:45:19 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-rvcLQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:45:19 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)) 17:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:45:19 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Yg2KIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:45:19 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)) 17:45:19 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)) 17:45:19 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)) 17:45:19 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)) 17:45:19 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:45:19 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:45:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:20 INFO - Downloading blessings-1.6.tar.gz 17:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:45:20 INFO - Installing collected packages: blessings 17:45:20 INFO - Running setup.py install for blessings 17:45:20 INFO - Successfully installed blessings 17:45:20 INFO - Cleaning up... 17:45:21 INFO - Return code: 0 17:45:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:21 INFO - Reading from file tmpfile_stdout 17:45:21 INFO - Current package versions: 17:45:21 INFO - blessings == 1.6 17:45:21 INFO - blobuploader == 1.2.4 17:45:21 INFO - docopt == 0.6.1 17:45:21 INFO - functools32 == 3.2.3-2 17:45:21 INFO - jsonschema == 2.5.1 17:45:21 INFO - manifestparser == 1.1 17:45:21 INFO - mozInstall == 1.12 17:45:21 INFO - mozcrash == 1.0 17:45:21 INFO - mozdebug == 0.1 17:45:21 INFO - mozdevice == 0.50 17:45:21 INFO - mozfile == 1.2 17:45:21 INFO - mozhttpd == 0.7 17:45:21 INFO - mozinfo == 0.9 17:45:21 INFO - mozleak == 0.1 17:45:21 INFO - mozlog == 3.4 17:45:21 INFO - moznetwork == 0.27 17:45:21 INFO - mozprocess == 0.25 17:45:21 INFO - mozprofile == 0.28 17:45:21 INFO - mozrunner == 6.13 17:45:21 INFO - mozscreenshot == 0.1 17:45:21 INFO - mozsystemmonitor == 0.3 17:45:21 INFO - moztest == 0.8 17:45:21 INFO - mozversion == 1.4 17:45:21 INFO - psutil == 3.1.1 17:45:21 INFO - requests == 1.2.3 17:45:21 INFO - wsgiref == 0.1.2 17:45:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:21 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:21 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:45:21 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:21 INFO - 'HOME': '/Users/cltbld', 17:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:21 INFO - 'LOGNAME': 'cltbld', 17:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:21 INFO - 'MOZ_NO_REMOTE': '1', 17:45:21 INFO - 'NO_EM_RESTART': '1', 17:45:21 INFO - 'PAGER': '/bin/cat', 17:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:21 INFO - 'PWD': '/builds/slave/test', 17:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:21 INFO - 'SHELL': '/bin/bash', 17:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:21 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:21 INFO - 'USER': 'cltbld', 17:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:21 INFO - 'XPC_FLAGS': '0x0', 17:45:21 INFO - 'XPC_SERVICE_NAME': '0', 17:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 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)) 17:45:21 INFO - Cleaning up... 17:45:21 INFO - Return code: 0 17:45:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:21 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/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 0x10902ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10902e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe985b4810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, '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.ffvuOrkNvL/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.od8xTFy1pV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 17:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:21 INFO - 'HOME': '/Users/cltbld', 17:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:21 INFO - 'LOGNAME': 'cltbld', 17:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:21 INFO - 'MOZ_NO_REMOTE': '1', 17:45:21 INFO - 'NO_EM_RESTART': '1', 17:45:21 INFO - 'PAGER': '/bin/cat', 17:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:21 INFO - 'PWD': '/builds/slave/test', 17:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:21 INFO - 'SHELL': '/bin/bash', 17:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:21 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:21 INFO - 'USER': 'cltbld', 17:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:21 INFO - 'XPC_FLAGS': '0x0', 17:45:21 INFO - 'XPC_SERVICE_NAME': '0', 17:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:45:21 INFO - Cleaning up... 17:45:21 INFO - Return code: 0 17:45:21 INFO - Running post-action listener: _resource_record_post_action 17:45:21 INFO - Running post-action listener: _start_resource_monitoring 17:45:21 INFO - Starting resource monitoring. 17:45:21 INFO - [mozharness: 2017-05-16 00:45:21.829971Z] Finished create-virtualenv step (success) 17:45:21 INFO - [mozharness: 2017-05-16 00:45:21.831020Z] Running install step. 17:45:21 INFO - Running pre-action listener: _resource_record_pre_action 17:45:21 INFO - Running main action method: install 17:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:22 INFO - Reading from file tmpfile_stdout 17:45:22 INFO - Detecting whether we're running mozinstall >=1.0... 17:45:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:45:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:45:22 INFO - Reading from file tmpfile_stdout 17:45:22 INFO - Output received: 17:45:22 INFO - Usage: mozinstall [options] installer 17:45:22 INFO - Options: 17:45:22 INFO - -h, --help show this help message and exit 17:45:22 INFO - -d DEST, --destination=DEST 17:45:22 INFO - Directory to install application into. [default: 17:45:22 INFO - "/builds/slave/test"] 17:45:22 INFO - --app=APP Application being installed. [default: firefox] 17:45:22 INFO - mkdir: /builds/slave/test/build/application 17:45:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:45:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:45:42 INFO - Reading from file tmpfile_stdout 17:45:42 INFO - Output received: 17:45:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:45:42 INFO - Running post-action listener: _resource_record_post_action 17:45:42 INFO - [mozharness: 2017-05-16 00:45:42.635829Z] Finished install step (success) 17:45:42 INFO - [mozharness: 2017-05-16 00:45:42.635967Z] Running run-tests step. 17:45:42 INFO - Running pre-action listener: _resource_record_pre_action 17:45:42 INFO - Running main action method: run_tests 17:45:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:42 INFO - 2017-05-15 17:45:42.651 screenresolution[3411:66474] starting screenresolution argv=screenresolution get 17:45:42 INFO - 2017-05-15 17:45:42.653 screenresolution[3411:66474] Display 0: 1600x1200x32@60 17:45:42 INFO - 2017-05-15 17:45:42.659 screenresolution[3412:66477] starting screenresolution argv=screenresolution list 17:45:42 INFO - Available Modes on Display 0 17:45:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:42 INFO - Intel Iris: 17:45:42 INFO - Chipset Model: Intel Iris 17:45:42 INFO - Type: GPU 17:45:42 INFO - Bus: Built-In 17:45:42 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:42 INFO - Vendor: Intel (0x8086) 17:45:42 INFO - Device ID: 0x0a2e 17:45:42 INFO - Revision ID: 0x0009 17:45:42 INFO - Displays: 17:45:42 INFO - Display: 17:45:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:42 INFO - Display Serial Number: 1600x1200 60 17:45:42 INFO - Main Display: Yes 17:45:42 INFO - Mirror: Off 17:45:42 INFO - Online: Yes 17:45:42 INFO - Rotation: Supported 17:45:42 INFO - Return code: 0 17:45:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:45:42 INFO - Python 2.7.3 17:45:42 INFO - Return code: 0 17:45:42 INFO - grabbing minidump binary from tooltool 17:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:45:42 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 0x108d3ad30>, '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 0x108ceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:45:42 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 17:45:42 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 17:45:42 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 17:45:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:45:43 INFO - Return code: 0 17:45:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:45:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:45:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:45:43 INFO - ENV: RUST_BACKTRACE is now 1 17:45:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:45:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:45:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 17:45:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 17:45:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvuOrkNvL/Render', 17:45:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:43 INFO - 'HOME': '/Users/cltbld', 17:45:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:43 INFO - 'LOGNAME': 'cltbld', 17:45:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:43 INFO - 'MOZ_NO_REMOTE': '1', 17:45:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:43 INFO - 'NO_EM_RESTART': '1', 17:45:43 INFO - 'PAGER': '/bin/cat', 17:45:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:43 INFO - 'PWD': '/builds/slave/test', 17:45:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:43 INFO - 'RUST_BACKTRACE': '1', 17:45:43 INFO - 'SHELL': '/bin/bash', 17:45:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.od8xTFy1pV/Listeners', 17:45:43 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 17:45:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:43 INFO - 'USER': 'cltbld', 17:45:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:43 INFO - 'XPC_FLAGS': '0x0', 17:45:43 INFO - 'XPC_SERVICE_NAME': '0', 17:45:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 17:45:43 INFO - mozversion application_buildid: 20170515162915 17:45:43 INFO - mozversion application_changeset: 3e166b6838931b3933ca274331f9e0e115af5cc0 17:45:43 INFO - mozversion application_display_name: Nightly 17:45:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:45:43 INFO - mozversion application_name: Firefox 17:45:43 INFO - mozversion application_remotingname: firefox 17:45:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:45:43 INFO - mozversion application_vendor: Mozilla 17:45:43 INFO - mozversion application_version: 55.0a1 17:45:43 INFO - mozversion platform_buildid: 20170515162915 17:45:43 INFO - mozversion platform_changeset: 3e166b6838931b3933ca274331f9e0e115af5cc0 17:45:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:45:43 INFO - mozversion platform_version: 55.0a1 17:45:43 INFO - using testdate: 1494895543 17:45:43 INFO - actual date: 1494895543 17:45:43 INFO - starting webserver on 'localhost:51779' 17:45:43 INFO - SUITE-START | Running 2 tests 17:45:43 INFO - TEST-START | dromaeo_css 17:45:43 INFO - operating with platform_type : mac_ 17:45:43 INFO - Initialising browser for dromaeo_css test... 17:45:43 INFO - TEST-INFO | started process 3422 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpXNKM6l/profile http://localhost:51779/getInfo.html) 17:45:43 INFO - PID 3422 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 17:45:45 INFO - PID 3422 | 2017-05-15 17:45:45.635 plugin-container[3424:66649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 17:45:45 INFO - PID 3422 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:45:45 INFO - PID 3422 | 2017-05-15 17:45:45.639 plugin-container[3424:66649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9733, name = 'com.apple.CFPasteboardClient' 17:45:45 INFO - PID 3422 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:45:45 INFO - PID 3422 | 2017-05-15 17:45:45.640 plugin-container[3424:66649] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:45:45 INFO - PID 3422 | __metrics Screen width/height:1600/1200 17:45:45 INFO - PID 3422 | colorDepth:24 17:45:45 INFO - PID 3422 | Browser inner width/height: 1024/691 17:45:45 INFO - PID 3422 | __metrics 17:45:46 INFO - TEST-INFO | 3422: exit 0 17:45:46 INFO - Browser initialized. 17:45:46 INFO - Running cycle 1/1 for dromaeo_css test... 17:45:46 INFO - TEST-INFO | started process 3426 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpXNKM6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:48 INFO - PID 3426 | 2017-05-15 17:45:48.533 plugin-container[3427:66801] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 17:45:48 INFO - PID 3426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:45:48 INFO - PID 3426 | 2017-05-15 17:45:48.534 plugin-container[3427:66801] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9427, name = 'com.apple.CFPasteboardClient' 17:45:48 INFO - PID 3426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:45:48 INFO - PID 3426 | 2017-05-15 17:45:48.534 plugin-container[3427:66801] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:45:49 INFO - PID 3426 | 2017-05-15 17:45:49.311 plugin-container[3428:66848] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 17:45:49 INFO - PID 3426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:45:49 INFO - PID 3426 | 2017-05-15 17:45:49.311 plugin-container[3428:66848] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 17:45:49 INFO - PID 3426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:45:49 INFO - PID 3426 | 2017-05-15 17:45:49.311 plugin-container[3428:66848] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:45:58 INFO - PID 3426 | RSS: Main: 235290624 17:45:58 INFO - PID 3426 | 17:49:02 INFO - PID 3426 | Cycle 1(1): loaded http://localhost:51779/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51779/tests/dromaeo/cssquery-ext.html) 17:49:04 INFO - PID 3426 | RSS: Main: 255975424 17:49:04 INFO - PID 3426 | 17:52:05 INFO - PID 3426 | Cycle 1(1): loaded http://localhost:51779/tests/dromaeo/cssquery-ext.html (next: http://localhost:51779/tests/dromaeo/cssquery-jquery.html) 17:52:06 INFO - PID 3426 | RSS: Main: 264855552 17:52:06 INFO - PID 3426 | 17:55:08 INFO - PID 3426 | Cycle 1(1): loaded http://localhost:51779/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51779/tests/dromaeo/cssquery-mootools.html) 17:55:08 INFO - PID 3426 | RSS: Main: 269094912 17:55:08 INFO - PID 3426 | 17:58:10 INFO - PID 3426 | Cycle 1(1): loaded http://localhost:51779/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51779/tests/dromaeo/cssquery-prototype.html) 17:58:11 INFO - PID 3426 | RSS: Main: 266833920 17:58:11 INFO - PID 3426 | 18:01:14 INFO - PID 3426 | Cycle 1(1): loaded http://localhost:51779/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51779/tests/dromaeo/cssquery-yui.html) 18:01:14 INFO - PID 3426 | RSS: Main: 268353536 18:01:14 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | Cycle 1(1): loaded http://localhost:51779/tests/dromaeo/cssquery-yui.html (next: http://localhost:51779/tests/dromaeo/cssquery-dojo.html) 18:04:16 INFO - PID 3426 | RSS: Main: 269836288 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | __start_tp_report 18:04:16 INFO - PID 3426 | _x_x_mozilla_page_load 18:04:16 INFO - PID 3426 | _x_x_mozilla_page_load_details 18:04:16 INFO - PID 3426 | |i|pagename|runs| 18:04:16 INFO - PID 3426 | |0;dojo.html;3524.1189702574356;3548.308079924414;3573.839177237024;3582;3607.422812350024;6112.503480924536;6117.938820611793;6222.408871157244;6229.252489701236;6233.1585235993125;5029.371328583927;5612.382637909826;6143.204841796983;6194.7831825886105;6252.6561039142825;5343.118385466393;5425.593080518957;5483.232347471358;5501.14732216507;5540.889182216353;5373.355197376301;5482.314710661168;5523.143912693539;5530.944690553103;5549.972250138743;5502.504774570288;5535.197396377523;5544.667319960795;5571.9442805572035;5593.720313984305;5295.735213239361;5395.7032363220005;5405.4324295949145;5474.151506516497;5474.562035037187;5307;5346.4386239445075;5377.542908852753;5469.945300546983;5479.835604931858;6000.069989151645;6019.217501724747;6154.5076393888385;6156.230471191101;6174;6620.768273110466;6661.800145995579;6675.46596272297;6681.231658359302;6682.665866706645;6573.1783527059115;6624.370684784938;6758.797236082925;6775.593464392152;6799.694013769392;6211.223597050369;6258.061290806416;6312.021636646281;6313.621182729051;6325.810225693144;6839.418649414855;7014.894776578355;7019.122609673791;7030.015797788314;7035.964820175866;6355.173827402408;6574.112494813139;6594.406503414716;6623.30455302196;6664.400203981568;6620;6729.360710732472;6732.427743641747;6763.695633696496;6820.863582728417;6419.486441084702;6502.089707440962;6684.63234522106;6687.164104486939;6703.564268322543;6575.079488871562;6595.4393876521035;6627.701753421107;6655.800325990228;6691.966540167268;5291.576673866081;5316.787328506816;5329.493698098674;5334.413214546396;5385.596080293915;4623.953760462352;4712.976435117803;4745;4748.4539277982785;4759.23852183649;5330.973345133327;6269.9936020473015;6288.842778930563;6310.8422289442215;6343.714532845941;6774.288699686561;6776.085228494188;6789.25318214996;6793.422559082434;6795.524313298021;6200.131981522591;6254.280757712831;6278.277998030194;6283.02613094971;6309.621422714652;5455.863603409867;5603.3836278009385;5977.701114944235;6022.57841951068;6028.246469191351;5621.353544342372;6870.381665650116;6891;6943.132108486439;6945.409640180439;5679.858003549944;6662.900056499253;6707.061011458302;6746.257911629815;6763.391294783493;6101.420365065312;6828.941514065328;6833.829154271183;6847.691853866688;6953.33943275388;6562.901556476752;6609.801705948829;6634.137562116894;6649.135612370361;6649.567778094409;5772.5959182856795;6473.320301368289;6731.966340168464;6766.594004359754;6769.695363708744;5261.657992230493;5353.75908084145;6321.49428045746;6584.144061272004;6603.372679595431;6490.967545162244;6577.309382514767;6589.077529145828;6597.934020659924;6611.30581288977;5247.868803279948;6273.404026617464;6410.967945160244;6443.710033048618;6471.611703297629;6348.143000694847;6539.803805885831;6659.134312539339;6665;6667.066610674412;6216.689165541615;6357.396047375492;6395.136656551306;6457.838554036187;6474.449671777763 18:04:16 INFO - PID 3426 | |1;ext.html;801.8997625296838;811.0915774332747;845.1590667286051;1223.198804782867;1268.3721557828876;38398.23203535927;38696.80651596738;39122.80438597803;39149.41275880864;39264.607353926454;39700.40449393254;40029.39882404708;40123.59567415141;40136.38772510474;40203.994900127436;7706.460547761659;7946.841063178733;8247.463914845546;8471.195236452528;8497.702580409687;7152.249013853549;8377.49735015898;8427.95786021069;8429.831403371929;8476.703315383962;7749.341305988998;8446.873296900552;8475.83048339033;8516.489010659381;8523.914760852374;8229.711960081399;8411.369147313946;8469.534175620338;8498.915010849874;8501.957490212571;8277.586120694;8310.8337833243;8341.499510029356;8367.163283671645;8378.952630921134;36965.63034369649;36997.81501092477;37045.81477092624;37094.07264818374;37132.62867371319;3551.751377403583;3557.3596752584526;3586.1214002569277;3587.3901436755814;3597.118705917041;30049.849750751324;30185.698143018508;30290.84854575713;30450.238744031354;30680.53979190314;3102.937941241185;3135.0751528299206;3140.8586613602442;3146.3707258548384;3152.7320177784914;283206;286104;287236.9999999979;294718;294883;45571;45602.543974560496;45654;45729.771351143034;45737.77131114323;37534.62465375366;47404;47414.28878566755;47436.7628161857;47472.7626361866;13958.953078519151;14012.159270443806;14150.929245353707;14172.787408188886;14186;11537.653870383901;11722.765544689059;11755.588554400554;11769.764604707858;11777.705557360963;9950.65172718966;13145;13208.933955330354;13209.33953302331;13265.734685306239;5947.5539334550085;5975.731092100866;5978.491828194565;6000.459958603659;6020.434686981416;1015.2943704125562;1030.7474668706066;1033.493588141805;1057.9682609521726;1061.830107182847;23165.07339706393;24318.635220471708;24450.633240501407;24480.632790508156;24551.508969820505;983;1126.245415571569;1131.3268605179906;1156.5084838943415;1175.7530918507039;11129.721756956142;11214.990650841464;12170.513179472722;12482.875171248354;12671.429785659668;259996;295539;297490;297860.51069744944;297989;36805.63194368022;47967.04065918662;48158.759206203744;48257.27614085792;48278.1377489807;1378.1455497591558;1382.1548233108913;1471;1552.2006166824094;1557.0813220200137;23183.53632927298;23972.760272397052;23986.40033999164;24148.39629009289;24204.39489012789;37012;37076.814615927826;37094.62905370912;37119.814400927826;37168;212184.69538261666;242174.78912605325;243637;244545;244745;31986;33462.49806252957;34358.14104647404;35800.104997375274;35989.28021439609;38693.03267418337;39227.607723922396;40599.797001015984;40775.79612101921;40793.79603101966;25729.614055788803;28621.570676439453;29896;30347.544786828657;31212;30443.39113217679;30893.845530772203;30963.535546966363;30968.69031309658;31437.37125257436;36199.914002579964;36820.447693284084;37160.256794864494;37397;37499.625003749614;30309.848450757607;31035.534466983467;31106.533401998535;31116.22209444782;31206;22611.208607698652;23760.52478950446;23867.52264954726;24275.514489710462;24328.75671243265 18:04:16 INFO - PID 3426 | |2;jquery.html;436.9126174765048;446.5088402756968;446.5712915601024;461.8776024353547;464.0649092079461;20992.580148397043;21533.892330538343;21902;22577.54844903101;22819.201327953524;21990.45023874404;22140.778592214072;22614.43463913403;22643.660345094835;22724.77275227247;6043.244594425697;6235.9376406235815;6237.126802247689;6460.58006229596;6776.119104516406;6296.4018418250425;6748.291429399916;6796.422304104157;6801.829954251159;6816.693248803791;6640.634765087918;6685.231198412196;6688.030235615833;6696.129503164606;6702.162229721285;4782.521747825207;6538.390434743518;6648.135742353512;6665.033570132328;6691.832704182386;6427.164468619097;6532.444742196919;6534.967325163344;6555.180602424697;6580.14458120446;28051.896107791996;28585.714142858513;28794.856025719946;29342.559861602094;29467.263318416997;2074.3362124120285;2354.434935615457;2362.810975122003;2362.8582285062953;2364.5152743687504;18422.53943651406;18514;18649;19106.522336941547;19141.458765598767;2277.0777834976693;2307.0541078157876;2374.0978428197345;2374.4538756086026;2376.1208352909534;164133;164364;165544.17227913783;165774.9999999988;166033.16983415006;19815.504612384517;23006.619602823885;23060;23446.179383721486;24496.755032449804;40120.99697507527;40563;40690.79654601708;40767.388489172685;40777.59222407798;9536.380135291323;10346.603208566747;10785.514651840685;11165.162612804072;11294.378809165575;7517.887231691529;8672.067770196707;8758.255548278341;8796.692115775917;8966.193042626195;9150;10738.248322617343;11311.604093856675;11364.090872730161;11461.713457163638;4109.404136400205;4113.9098138993195;4798.04039192156;4819.566239038433;4858.983521838398;800.5836964778283;919.7056941778567;980.9803803923882;983.1839573154265;1001.2040427859723;18878.811211887707;19546.60906781885;20850.687239691415;21009;21106;977.2426369563503;988.8961659025699;1005.3967619428431;1007.5466040281756;1019.8521214423866;8316.75248712698;9992.300538962203;10318.535665895053;10682.252242343116;10798.946005269923;465103;503069;512120;516887;519134;120117.39941300238;120723;121054;121326;121551.17673234908;1000.3997601439079;1155.3241353807948;1226.999999999982;1239.3617287097152;1253.4296894912793;20855.582888341843;21745.782542174376;22002.88998554997;22168.778312217317;22176.002079906764;50450;50682.493175069256;55220.72389638026;55346;56593;54359.45640543544;75677.24322756701;76660;76891.2310876884;76921.61539192268;8663.696770612998;8742.737717868475;8750.474971501475;8781.121887811169;9136.26909847224;13685.931570342085;13723.725525489634;13868.306584670929;14003.299835008413;14078.507252246121;9809.822821261494;11787.410629468664;11832.940835295769;12002.75994480123;12112.728163543054;10914.126869850552;10929.071028962628;10939.288946218789;11238.7752244953;11338.943305283421;11871.347075910908;11906.047516198856;11917.284962902253;12037.638870833889;12164.513419463476;6643.7674681385915;7322.780316590511;8535.634298512208;8805.515696636747;8906.287497000094;8963.955180224057;9699;11807.409629518661;11934.761304773681;12008 18:04:16 INFO - PID 3426 | |3;mootools.html;1417.4542801021607;1457.5408746244939;1478.3864696151097;1479.2825479642372;1487.1597547385727;10128.341657792238;10353.689389318313;11688.298702077882;11924.344161071149;12187.756244875098;11709.297442153476;11789;11831;11895.821562676567;11908.702282442944;2505.674262345897;2559.11710459892;2562.987185064072;2565.615157726337;2576.8840402181945;2115.894205289737;2471.283327834926;2536.1630661881627;2540.707818600866;2557.0794513974956;2436.5857804173334;2439.560879041772;2443.240866576062;2450.546648869965;2455.484348286862;2039.4697378681576;2520.042383894118;2523.9747602524108;2525.83582067166;2532.607445845897;2490.1160088168804;2493.2146373892165;2495.46347535279;2503.6119401492797;2514.1451906351926;9650.469224192666;11498.310101393943;11779.7055073623;11822.408879555987;11840.46717897688;5667.943320566783;6515.804525864231;6749.392554670103;6758.729650813962;6764.458843292525;4026.71812973092;4265.466816647919;4282.593153650399;4313.84901528445;4361.302191649321;1224.5897624295872;1252.1235135405252;1295.2099219476113;1299.5646458436436;1305.1777380250383;16386.426475073316;16472.505824825155;16481.25834337458;16484.175791210393;16500.092494912777;4869.9269510957365;5778.451047150514;6071.878562428815;6176.969115154396;6190.907136392958;13804.585862424143;13804.723905521832;13806.17162970225;13826.23955682427;13835.239061851493;6653.334666533405;6766.69549870257;6779.864402711918;6787.898181527281;6790.675818215499;5918.8816223675285;6180.47465965398;6209.844753881099;6247.625142491465;6252.499800015988;3645.8177091145335;4120.793960301973;4292.334688123346;4340.848070317524;4373.825046998148;1287.1569122224957;1287.9034072444604;1305.4386613755987;1306.8627794081674;1316.5457917018614;741.2476336518438;788.4244501513926;797.6928882380325;817.9018517777905;827.2182787266096;5207.479252074838;5666.291713535808;6250.999840025665;6984.650767461607;7052.294770523009;821.4291067708006;948.6395169835556;963.4363897120123;967.7435479597913;994.6021591363512;3496.5629296337956;4238.724482908601;4275.593818587229;4343.218220720238;4472.39622650938;14466.05970611907;14799.112053276838;14817.40730370773;14875.851241487662;14930.25348732559;14113.85886141146;14694.706105878036;14769.187694676895;14784.852151478563;14803.185824779524;7264.818379540554;8181.468204566684;8205.343572514184;8528.872066918877;8580.828383432421;6521.413072823469;6674.866502669823;6718.93281067183;6817.897731533932;6843.623600702009;10918.563257469612;13159.144655597356;13165.683431656758;13264.734705305647;13434.462621495031;13340.19958802475;13529.188248704715;13532.93233533826;13616.319184040956;13871.514496992557;8070.959645201737;8579.313654907717;8842.867356989778;9003.729888103368;9213.631454741735;10954.945225274141;11116.555337786398;11120.833187502032;11122.165837562054;11137;8970.551472426483;9201.263898887939;9237.03011183816;9309.627614895327;9456.432614043179;8981.910180898107;9036.45781253127;9048.321375896965;9061.456312621263;9077.500737459502;8095.15000924918;8127.065387480516;8209.05595856484;8214.014318281845;8216.46592971455;4608.608268297098;5748.770049198152;5753;6005.3694362091355;6115.449609535163;5958.285005799332;6006.48944839676;6028.125921741411;6216.53375996793;6299 18:04:16 INFO - PID 3426 | |4;prototype.html;329.84497286275456;405.92490389277987;408.21418768869955;413.9730917490361;415.57818813903884;5691;5804.361520232771;6087.208662873832;6154;6323.336049714782;5948.672822994734;6008.858316919782;6206.1931948846695;6222.875542489159;6234.937650623492;3239.6274428440797;3638.890833274993;4078.8572399966006;4461.4869290031575;4492.955070449287;3982.7255278310954;4211.915761684764;4492.0791237796175;4495.1459222747735;4540.636749060084;3846.4614953906475;4457.375967364572;4461.531539188387;4462.995825939154;4465.5311192324825;4300.290452075404;4419.315006174048;4470.575295346936;4478.97760511197;4493.618042466394;3810.6951443884423;3815.8282877270312;3816.072883193997;3819.7135214858718;3828.3300422425964;5996.580239383245;6023.066424704178;6028.278469506513;6039.161594050789;6043.4975942922365;5223.791048358061;5303.806643505181;5348.973255133738;5371.516563509303;5396.811111611075;5424.321959755031;5889.8822023559715;6007.008843540854;6617.139771829631;6640.966795166041;3951.881443556698;4213.9578604213875;4364.432623758923;4369.912601747979;4386.8903277418;5337.599680024014;5996.820095397145;6104.633721976696;6826.795196144124;7002.3697867192195;3836.4437156612134;4312.784360781949;4416.183006143874;4423.788105947026;4427.114577084596;3774.6036666150208;4495.550444955544;4500.347449619852;4518.322251662277;4527.796249168795;5162.483751624807;5217.765200565984;5284.7093409862055;5379.246905433242;5386.407495175527;6696.06255124283;6710.127683401127;6715.1941766988275;6742.764003259862;6758.628275444898;1254.3728135932033;1403.614006148297;1413.7172565486944;1544.343653947068;1549.9922500387427;847.656699036891;979.2361957672885;998.2912132385942;998.5756053677159;1015.6851376073441;6318.589291696025;6329.208848893888;6330.3669633036325;6336;6351.777687780906;6668.766593169215;6885.414739747178;6891.657546090142;6894.794430205163;6933.54931929433;5254.605904557174;5461.890762184734;6154.5076393888385;6242.406971337716;6291.049774569898;3187.059817353886;3403.7957722536726;3528.364894318996;3564.287142571496;3611.8916432507067;5832.358440571534;6397.296297407282;6438.7102580384;6490.610563366213;7119.964400177966;6504.252011018793;7096.53872498286;7128.287171282934;7131.429485641136;7145.606991615511;5928.436798504135;6481.351864813575;6778.389944904982;7183.85058390655;7261.092363454568;6121.908171377522;6900.413464855544;7004.649767511706;7028.367446929597;7049.577025378494;5592.916106258491;6119.816405507842;6523.869522609426;6678.532502724763;6885.173779146535;5861.706914654335;6080.331163572092;7049.859002820018;7072.823179420556;7182.425405967615;3683.9079023024638;4146.398772178078;4435.778211089368;4476.060027394284;4495;5271.736413179402;5401.918971215514;5718.456746609066;6813.488988325995;7033.648317583998;6241.500679945684;6615.04081908111;6715.5970641759695;6736.867356679168;6738.09035780183;5533.6956467393065;6153.29237137717;6614.338566143347;6744.763933262312;6773.593584384953;5247.632665713363;5370.677759334452;6515.990021546668;6643;6657.868162412305;5692.914606280826;6030.849228769316;6255.499560035095;6779.661016949034;6784.6946887389195;6141.477974372228;6473.838154045998;6535.411812936859;6546.509011824037;6557.114869457423 18:04:16 INFO - PID 3426 | |5;yui.html;825.7068740597092;835.7659855240532;855.4696088425173;857.2242120880601;871.2594294849379;15643.92178039111;16149.43476978306;16574.83425165748;16609.418670346542;18361;14960;16345.427910023152;17759.378421755242;17883;18231.17959691817;990.1732053735121;1004.9899501004969;1009.9747506312327;1017.0592202212961;1023.6673081248587;1009.5759780892043;1020.1787561013381;1027.7841653252788;1032.9876720813606;1038.8493668418075;1069.059227879465;1080.6595922284494;1089.0525243038567;1089.8855620159889;1095.5343978809015;1081.4214395298477;1082.0910435234362;1085.4301491716833;1088.7659153282082;1094.370736826324;787.5904529644553;810.2828996338225;812.8130529978138;812.9918700812975;813.1096449387911;16539.25573349202;16847;17075.914620426818;17282.395116171;17289.48131556055;16739.246733897005;16778;17140.65718685632;17172.484825455256;17250.0512471814;13082.672933176651;13219.272939988297;13348.132371395925;13457.865421345858;13622.795658065137;3501.5973163086314;3530.2409981853766;3538.097785064818;3551.1122219445137;3596.730245231618;128823.35588322092;128868;129091.89994550296;129484.35257823745;131371.34314328464;11338.489767960462;12922.870771292355;13166.670833229246;13361;13376.929845612327;21170.47073823136;25220.369490762878;25853.35366615812;25938.481230375288;25978.090766823072;6467.967660161669;7938.166492518318;7973.641186146638;7975;7998.360131189491;3991.1419044905483;4007.238624661305;4036.273470775289;4064.837406503707;4068.613481719232;4440.267355886307;4514.616257618073;4518.819247230074;4523.389342438794;4524.773761311987;917.8393781088283;952.3428834104584;959.491469521155;967.7725734452398;968.7868668892833;658.8649326887977;659.7031335898864;674.3458844920419;678.176016140382;683.1597135523235;10676.786464270672;11613.245139065933;11976.20356946465;11986.460609272604;12028.63914082579;636.5003472274335;642.328766439063;650.72344253692;650.9902351464732;673.397309408077;5319;5540;5591.776328946797;5595.272614560163;6039.124326972652;85894.14105858987;87493.56253218821;89371;97316.51341743246;98548;80658.59670701726;83803.16196838075;89377.21245575052;90356.54821725849;91675.54162229146;69986.30013699798;70963.2903670967;70988;71556.284437156;71794;12142.664306926408;12225.388730563258;12267.509299627915;12288.262704237774;12462.750744985231;39964.60035399609;40275.99310017156;40657.1868562633;41057.58942410657;41298;51399.74300128625;51855.740721296155;52225;52345.21482177846;52375;16172.110533920748;16281.51155465338;16623.667526649642;16807.243674034453;16858.57853553622;17796;18013.369532066314;18542.81457185411;18622.813771862107;18722.438326850217;15026.24868756536;15537.06777593348;16388.344466221693;17139.228734706718;17181.484555463358;13301.26843023643;16578.17109144562;16837;16901.32394704198;17065.829341706423;15837.920810395874;16535.511803937705;16722.498325050266;16745.581360466087;16901;11663.59177428786;11724.120690948042;11958.64124076279;12117.75764484723;12341.691457713629;10794.406307653155;13337.599872003855;13498.52755153565;13514.864851351755;13785.931070344985 18:04:16 INFO - PID 3426 | __end_tp_report 18:04:16 INFO - PID 3426 | __start_cc_report 18:04:16 INFO - PID 3426 | _x_x_mozilla_cycle_collect,344 18:04:16 INFO - PID 3426 | __end_cc_report 18:04:16 INFO - PID 3426 | __startTimestamp1494896656611__endTimestamp 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | ------- Summary: start ------- 18:04:16 INFO - PID 3426 | Number of tests: 6 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | [#0] dojo.html Cycles:165 Average:6118.90 Median:6321.49 stddev:738.27 (11.7%) stddev-sans-first:711.92 18:04:16 INFO - PID 3426 | Values: 3524.1 3548.3 3573.8 3582.0 3607.4 6112.5 6117.9 6222.4 6229.3 6233.2 5029.4 5612.4 6143.2 6194.8 6252.7 5343.1 5425.6 5483.2 5501.1 5540.9 5373.4 5482.3 5523.1 5530.9 5550.0 5502.5 5535.2 5544.7 5571.9 5593.7 5295.7 5395.7 5405.4 5474.2 5474.6 5307.0 5346.4 5377.5 5469.9 5479.8 6000.1 6019.2 6154.5 6156.2 6174.0 6620.8 6661.8 6675.5 6681.2 6682.7 6573.2 6624.4 6758.8 6775.6 6799.7 6211.2 6258.1 6312.0 6313.6 6325.8 6839.4 7014.9 7019.1 7030.0 7036.0 6355.2 6574.1 6594.4 6623.3 6664.4 6620.0 6729.4 6732.4 6763.7 6820.9 6419.5 6502.1 6684.6 6687.2 6703.6 6575.1 6595.4 6627.7 6655.8 6692.0 5291.6 5316.8 5329.5 5334.4 5385.6 4624.0 4713.0 4745.0 4748.5 4759.2 5331.0 6270.0 6288.8 6310.8 6343.7 6774.3 6776.1 6789.3 6793.4 6795.5 6200.1 6254.3 6278.3 6283.0 6309.6 5455.9 5603.4 5977.7 6022.6 6028.2 5621.4 6870.4 6891.0 6943.1 6945.4 5679.9 6662.9 6707.1 6746.3 6763.4 6101.4 6828.9 6833.8 6847.7 6953.3 6562.9 6609.8 6634.1 6649.1 6649.6 5772.6 6473.3 6732.0 6766.6 6769.7 5261.7 5353.8 6321.5 6584.1 6603.4 6491.0 6577.3 6589.1 6597.9 6611.3 5247.9 6273.4 6411.0 6443.7 6471.6 6348.1 6539.8 6659.1 6665.0 6667.1 6216.7 6357.4 6395.1 6457.8 6474.4 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | [#1] ext.html Cycles:180 Average:42228.61 Median:32724.25 stddev:71494.11 (218.5%) stddev-sans-first:71627.01 18:04:16 INFO - PID 3426 | Values: 801.9 811.1 845.2 1223.2 1268.4 38398.2 38696.8 39122.8 39149.4 39264.6 39700.4 40029.4 40123.6 40136.4 40204.0 7706.5 7946.8 8247.5 8471.2 8497.7 7152.2 8377.5 8428.0 8429.8 8476.7 7749.3 8446.9 8475.8 8516.5 8523.9 8229.7 8411.4 8469.5 8498.9 8502.0 8277.6 8310.8 8341.5 8367.2 8379.0 36965.6 36997.8 37045.8 37094.1 37132.6 3551.8 3557.4 3586.1 3587.4 3597.1 30049.8 30185.7 30290.8 30450.2 30680.5 3102.9 3135.1 3140.9 3146.4 3152.7 283206.0 286104.0 287237.0 294718.0 294883.0 45571.0 45602.5 45654.0 45729.8 45737.8 37534.6 47404.0 47414.3 47436.8 47472.8 13959.0 14012.2 14150.9 14172.8 14186.0 11537.7 11722.8 11755.6 11769.8 11777.7 9950.7 13145.0 13208.9 13209.3 13265.7 5947.6 5975.7 5978.5 6000.5 6020.4 1015.3 1030.7 1033.5 1058.0 1061.8 23165.1 24318.6 24450.6 24480.6 24551.5 983.0 1126.2 1131.3 1156.5 1175.8 11129.7 11215.0 12170.5 12482.9 12671.4 259996.0 295539.0 297490.0 297860.5 297989.0 36805.6 47967.0 48158.8 48257.3 48278.1 1378.1 1382.2 1471.0 1552.2 1557.1 23183.5 23972.8 23986.4 24148.4 24204.4 37012.0 37076.8 37094.6 37119.8 37168.0 212184.7 242174.8 243637.0 244545.0 244745.0 31986.0 33462.5 34358.1 35800.1 35989.3 38693.0 39227.6 40599.8 40775.8 40793.8 25729.6 28621.6 29896.0 30347.5 31212.0 30443.4 30893.8 30963.5 30968.7 31437.4 36199.9 36820.4 37160.3 37397.0 37499.6 30309.8 31035.5 31106.5 31116.2 31206.0 22611.2 23760.5 23867.5 24275.5 24328.8 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | [#2] jquery.html Cycles:180 Average:35388.88 Median:13436.44 stddev:86099.64 (640.8%) stddev-sans-first:86301.18 18:04:16 INFO - PID 3426 | Values: 436.9 446.5 446.6 461.9 464.1 20992.6 21533.9 21902.0 22577.5 22819.2 21990.5 22140.8 22614.4 22643.7 22724.8 6043.2 6235.9 6237.1 6460.6 6776.1 6296.4 6748.3 6796.4 6801.8 6816.7 6640.6 6685.2 6688.0 6696.1 6702.2 4782.5 6538.4 6648.1 6665.0 6691.8 6427.2 6532.4 6535.0 6555.2 6580.1 28051.9 28585.7 28794.9 29342.6 29467.3 2074.3 2354.4 2362.8 2362.9 2364.5 18422.5 18514.0 18649.0 19106.5 19141.5 2277.1 2307.1 2374.1 2374.5 2376.1 164133.0 164364.0 165544.2 165775.0 166033.2 19815.5 23006.6 23060.0 23446.2 24496.8 40121.0 40563.0 40690.8 40767.4 40777.6 9536.4 10346.6 10785.5 11165.2 11294.4 7517.9 8672.1 8758.3 8796.7 8966.2 9150.0 10738.2 11311.6 11364.1 11461.7 4109.4 4113.9 4798.0 4819.6 4859.0 800.6 919.7 981.0 983.2 1001.2 18878.8 19546.6 20850.7 21009.0 21106.0 977.2 988.9 1005.4 1007.5 1019.9 8316.8 9992.3 10318.5 10682.3 10798.9 465103.0 503069.0 512120.0 516887.0 519134.0 120117.4 120723.0 121054.0 121326.0 121551.2 1000.4 1155.3 1227.0 1239.4 1253.4 20855.6 21745.8 22002.9 22168.8 22176.0 50450.0 50682.5 55220.7 55346.0 56593.0 54359.5 75677.2 76660.0 76891.2 76921.6 8663.7 8742.7 8750.5 8781.1 9136.3 13685.9 13723.7 13868.3 14003.3 14078.5 9809.8 11787.4 11832.9 12002.8 12112.7 10914.1 10929.1 10939.3 11238.8 11338.9 11871.3 11906.0 11917.3 12037.6 12164.5 6643.8 7322.8 8535.6 8805.5 8906.3 8964.0 9699.0 11807.4 11934.8 12008.0 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | [#3] mootools.html Cycles:180 Average:7098.04 Median:3836.27 stddev:4516.65 (117.7%) stddev-sans-first:4509.15 18:04:16 INFO - PID 3426 | Values: 1417.5 1457.5 1478.4 1479.3 1487.2 10128.3 10353.7 11688.3 11924.3 12187.8 11709.3 11789.0 11831.0 11895.8 11908.7 2505.7 2559.1 2563.0 2565.6 2576.9 2115.9 2471.3 2536.2 2540.7 2557.1 2436.6 2439.6 2443.2 2450.5 2455.5 2039.5 2520.0 2524.0 2525.8 2532.6 2490.1 2493.2 2495.5 2503.6 2514.1 9650.5 11498.3 11779.7 11822.4 11840.5 5667.9 6515.8 6749.4 6758.7 6764.5 4026.7 4265.5 4282.6 4313.8 4361.3 1224.6 1252.1 1295.2 1299.6 1305.2 16386.4 16472.5 16481.3 16484.2 16500.1 4869.9 5778.5 6071.9 6177.0 6190.9 13804.6 13804.7 13806.2 13826.2 13835.2 6653.3 6766.7 6779.9 6787.9 6790.7 5918.9 6180.5 6209.8 6247.6 6252.5 3645.8 4120.8 4292.3 4340.8 4373.8 1287.2 1287.9 1305.4 1306.9 1316.5 741.2 788.4 797.7 817.9 827.2 5207.5 5666.3 6251.0 6984.7 7052.3 821.4 948.6 963.4 967.7 994.6 3496.6 4238.7 4275.6 4343.2 4472.4 14466.1 14799.1 14817.4 14875.9 14930.3 14113.9 14694.7 14769.2 14784.9 14803.2 7264.8 8181.5 8205.3 8528.9 8580.8 6521.4 6674.9 6718.9 6817.9 6843.6 10918.6 13159.1 13165.7 13264.7 13434.5 13340.2 13529.2 13532.9 13616.3 13871.5 8071.0 8579.3 8842.9 9003.7 9213.6 10954.9 11116.6 11120.8 11122.2 11137.0 8970.6 9201.3 9237.0 9309.6 9456.4 8981.9 9036.5 9048.3 9061.5 9077.5 8095.2 8127.1 8209.1 8214.0 8216.5 4608.6 5748.8 5753.0 6005.4 6115.4 5958.3 6006.5 6028.1 6216.5 6299.0 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | [#4] prototype.html Cycles:180 Average:5254.03 Median:5996.70 stddev:1694.39 (28.3%) stddev-sans-first:1658.34 18:04:16 INFO - PID 3426 | Values: 329.8 405.9 408.2 414.0 415.6 5691.0 5804.4 6087.2 6154.0 6323.3 5948.7 6008.9 6206.2 6222.9 6234.9 3239.6 3638.9 4078.9 4461.5 4493.0 3982.7 4211.9 4492.1 4495.1 4540.6 3846.5 4457.4 4461.5 4463.0 4465.5 4300.3 4419.3 4470.6 4479.0 4493.6 3810.7 3815.8 3816.1 3819.7 3828.3 5996.6 6023.1 6028.3 6039.2 6043.5 5223.8 5303.8 5349.0 5371.5 5396.8 5424.3 5889.9 6007.0 6617.1 6641.0 3951.9 4214.0 4364.4 4369.9 4386.9 5337.6 5996.8 6104.6 6826.8 7002.4 3836.4 4312.8 4416.2 4423.8 4427.1 3774.6 4495.6 4500.3 4518.3 4527.8 5162.5 5217.8 5284.7 5379.2 5386.4 6696.1 6710.1 6715.2 6742.8 6758.6 1254.4 1403.6 1413.7 1544.3 1550.0 847.7 979.2 998.3 998.6 1015.7 6318.6 6329.2 6330.4 6336.0 6351.8 6668.8 6885.4 6891.7 6894.8 6933.5 5254.6 5461.9 6154.5 6242.4 6291.0 3187.1 3403.8 3528.4 3564.3 3611.9 5832.4 6397.3 6438.7 6490.6 7120.0 6504.3 7096.5 7128.3 7131.4 7145.6 5928.4 6481.4 6778.4 7183.9 7261.1 6121.9 6900.4 7004.6 7028.4 7049.6 5592.9 6119.8 6523.9 6678.5 6885.2 5861.7 6080.3 7049.9 7072.8 7182.4 3683.9 4146.4 4435.8 4476.1 4495.0 5271.7 5401.9 5718.5 6813.5 7033.6 6241.5 6615.0 6715.6 6736.9 6738.1 5533.7 6153.3 6614.3 6744.8 6773.6 5247.6 5370.7 6516.0 6643.0 6657.9 5692.9 6030.8 6255.5 6779.7 6784.7 6141.5 6473.8 6535.4 6546.5 6557.1 18:04:16 INFO - PID 3426 | 18:04:16 INFO - PID 3426 | [#5] yui.html Cycles:180 Average:21031.35 Median:17777.69 stddev:29233.72 (164.4%) stddev-sans-first:29276.35 18:04:16 INFO - PID 3426 | Values: 825.7 835.8 855.5 857.2 871.3 15643.9 16149.4 16574.8 16609.4 18361.0 14960.0 16345.4 17759.4 17883.0 18231.2 990.2 1005.0 1010.0 1017.1 1023.7 1009.6 1020.2 1027.8 1033.0 1038.8 1069.1 1080.7 1089.1 1089.9 1095.5 1081.4 1082.1 1085.4 1088.8 1094.4 787.6 810.3 812.8 813.0 813.1 16539.3 16847.0 17075.9 17282.4 17289.5 16739.2 16778.0 17140.7 17172.5 17250.1 13082.7 13219.3 13348.1 13457.9 13622.8 3501.6 3530.2 3538.1 3551.1 3596.7 128823.4 128868.0 129091.9 129484.4 131371.3 11338.5 12922.9 13166.7 13361.0 13376.9 21170.5 25220.4 25853.4 25938.5 25978.1 6468.0 7938.2 7973.6 7975.0 7998.4 3991.1 4007.2 4036.3 4064.8 4068.6 4440.3 4514.6 4518.8 4523.4 4524.8 917.8 952.3 959.5 967.8 968.8 658.9 659.7 674.3 678.2 683.2 10676.8 11613.2 11976.2 11986.5 12028.6 636.5 642.3 650.7 651.0 673.4 5319.0 5540.0 5591.8 5595.3 6039.1 85894.1 87493.6 89371.0 97316.5 98548.0 80658.6 83803.2 89377.2 90356.5 91675.5 69986.3 70963.3 70988.0 71556.3 71794.0 12142.7 12225.4 12267.5 12288.3 12462.8 39964.6 40276.0 40657.2 41057.6 41298.0 51399.7 51855.7 52225.0 52345.2 52375.0 16172.1 16281.5 16623.7 16807.2 16858.6 17796.0 18013.4 18542.8 18622.8 18722.4 15026.2 15537.1 16388.3 17139.2 17181.5 13301.3 16578.2 16837.0 16901.3 17065.8 15837.9 16535.5 16722.5 16745.6 16901.0 11663.6 11724.1 11958.6 12117.8 12341.7 10794.4 13337.6 13498.5 13514.9 13785.9 18:04:16 INFO - PID 3426 | -------- Summary: end -------- 18:04:16 INFO - PID 3426 | 18:04:17 INFO - TEST-INFO | 3426: exit 0 18:04:17 INFO - TEST-OK | dromaeo_css | took 1114163ms 18:04:17 INFO - TEST-START | kraken 18:04:17 INFO - operating with platform_type : mac_ 18:04:17 INFO - Initialising browser for kraken test... 18:04:17 INFO - TEST-INFO | started process 3438 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpsWDUYh/profile http://localhost:51779/getInfo.html) 18:04:17 INFO - PID 3438 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:04:19 INFO - PID 3438 | 2017-05-15 18:04:19.773 plugin-container[3439:69047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:04:19 INFO - PID 3438 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:19 INFO - PID 3438 | 2017-05-15 18:04:19.777 plugin-container[3439:69047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x962b, name = 'com.apple.CFPasteboardClient' 18:04:19 INFO - PID 3438 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:19 INFO - PID 3438 | 2017-05-15 18:04:19.777 plugin-container[3439:69047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:19 INFO - PID 3438 | __metrics Screen width/height:1600/1200 18:04:19 INFO - PID 3438 | colorDepth:24 18:04:19 INFO - PID 3438 | Browser inner width/height: 1024/691 18:04:19 INFO - PID 3438 | __metrics 18:04:21 INFO - TEST-INFO | 3438: exit 0 18:04:21 INFO - Browser initialized. 18:04:21 INFO - Running cycle 1/1 for kraken test... 18:04:21 INFO - TEST-INFO | started process 3441 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpsWDUYh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:22 INFO - PID 3441 | 2017-05-15 18:04:22.701 plugin-container[3442:69190] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 18:04:22 INFO - PID 3441 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:22 INFO - PID 3441 | 2017-05-15 18:04:22.701 plugin-container[3442:69190] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 18:04:22 INFO - PID 3441 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:22 INFO - PID 3441 | 2017-05-15 18:04:22.701 plugin-container[3442:69190] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:23 INFO - PID 3441 | 2017-05-15 18:04:23.509 plugin-container[3443:69239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 18:04:23 INFO - PID 3441 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:23 INFO - PID 3441 | 2017-05-15 18:04:23.510 plugin-container[3443:69239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x941b, name = 'com.apple.CFPasteboardClient' 18:04:23 INFO - PID 3441 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:23 INFO - PID 3441 | 2017-05-15 18:04:23.510 plugin-container[3443:69239] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:32 INFO - PID 3441 | RSS: Main: 236720128 18:04:32 INFO - PID 3441 | 18:04:35 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51779/tests/kraken/driver.html?testName=audio-beat-detection) 18:04:36 INFO - PID 3441 | RSS: Main: 248238080 18:04:36 INFO - PID 3441 | 18:04:38 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51779/tests/kraken/driver.html?testName=audio-dft) 18:04:39 INFO - PID 3441 | RSS: Main: 256724992 18:04:39 INFO - PID 3441 | 18:04:43 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51779/tests/kraken/driver.html?testName=audio-fft) 18:04:43 INFO - PID 3441 | RSS: Main: 258019328 18:04:43 INFO - PID 3441 | 18:04:45 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51779/tests/kraken/driver.html?testName=audio-oscillator) 18:04:46 INFO - PID 3441 | RSS: Main: 259915776 18:04:46 INFO - PID 3441 | 18:04:48 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51779/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:04:48 INFO - PID 3441 | RSS: Main: 259944448 18:04:48 INFO - PID 3441 | 18:04:51 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51779/tests/kraken/driver.html?testName=imaging-darkroom) 18:04:52 INFO - PID 3441 | RSS: Main: 265568256 18:04:52 INFO - PID 3441 | 18:04:55 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51779/tests/kraken/driver.html?testName=imaging-desaturate) 18:04:55 INFO - PID 3441 | RSS: Main: 266637312 18:04:55 INFO - PID 3441 | 18:04:58 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51779/tests/kraken/driver.html?testName=json-parse-financial) 18:04:59 INFO - PID 3441 | RSS: Main: 265818112 18:04:59 INFO - PID 3441 | 18:05:00 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51779/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:05:01 INFO - PID 3441 | RSS: Main: 265637888 18:05:01 INFO - PID 3441 | 18:05:03 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:05:03 INFO - PID 3441 | RSS: Main: 265404416 18:05:03 INFO - PID 3441 | 18:05:05 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:05:06 INFO - PID 3441 | RSS: Main: 265416704 18:05:06 INFO - PID 3441 | 18:05:09 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:05:10 INFO - PID 3441 | RSS: Main: 265142272 18:05:10 INFO - PID 3441 | 18:05:13 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:05:13 INFO - PID 3441 | RSS: Main: 265392128 18:05:13 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | Cycle 1(1): loaded http://localhost:51779/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51779/tests/kraken/driver.html?testName=ai-astar) 18:05:15 INFO - PID 3441 | RSS: Main: 267534336 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | __start_tp_report 18:05:15 INFO - PID 3441 | _x_x_mozilla_page_load 18:05:15 INFO - PID 3441 | _x_x_mozilla_page_load_details 18:05:15 INFO - PID 3441 | |i|pagename|runs| 18:05:15 INFO - PID 3441 | |0;ai-astar;102;115;116;115;114;115;113;114;113;114 18:05:15 INFO - PID 3441 | |1;audio-beat-detection;141;111;110;146;111;111;112;109;126;135 18:05:15 INFO - PID 3441 | |2;audio-dft;230;279;199;197;217;265;267;280;223;199 18:05:15 INFO - PID 3441 | |3;audio-fft;81;58;58;85;60;61;79;49;72;82 18:05:15 INFO - PID 3441 | |4;audio-oscillator;92;71;85;71;69;72;88;69;69;91 18:05:15 INFO - PID 3441 | |5;imaging-gaussian-blur;88;88;92;87;89;89;89;88;89;99 18:05:15 INFO - PID 3441 | |6;imaging-darkroom;95;97;96;95;95;96;96;96;95;95 18:05:15 INFO - PID 3441 | |7;imaging-desaturate;90;95;92;92;91;91;92;94;91;91 18:05:15 INFO - PID 3441 | |8;json-parse-financial;48;48;48;49;50;48;49;48;51;49 18:05:15 INFO - PID 3441 | |9;json-stringify-tinderbox;55;59;62;72;66;62;65;74;87;90 18:05:15 INFO - PID 3441 | |10;stanford-crypto-aes;83;82;84;82;84;82;81;86;84;82 18:05:15 INFO - PID 3441 | |11;stanford-crypto-ccm;135;136;134;141;138;139;155;143;192;150 18:05:15 INFO - PID 3441 | |12;stanford-crypto-pbkdf2;193;186;174;198;183;188;184;179;178;176 18:05:15 INFO - PID 3441 | |13;stanford-crypto-sha256-iterative;71;69;66;68;68;69;70;64;56;85 18:05:15 INFO - PID 3441 | __end_tp_report 18:05:15 INFO - PID 3441 | __start_cc_report 18:05:15 INFO - PID 3441 | _x_x_mozilla_cycle_collect,926 18:05:15 INFO - PID 3441 | __end_cc_report 18:05:15 INFO - PID 3441 | __startTimestamp1494896715794__endTimestamp 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | ------- Summary: start ------- 18:05:15 INFO - PID 3441 | Number of tests: 14 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.50 stddev:4.01 (3.5%) stddev-sans-first:1.00 18:05:15 INFO - PID 3441 | Values: 102.0 115.0 116.0 115.0 114.0 115.0 113.0 114.0 113.0 114.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:119.00 stddev:14.50 (12.2%) stddev-sans-first:13.49 18:05:15 INFO - PID 3441 | Values: 141.0 111.0 110.0 146.0 111.0 111.0 112.0 109.0 126.0 135.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#2] audio-dft Cycles:10 Average:235.60 Median:247.50 stddev:34.01 (13.7%) stddev-sans-first:36.01 18:05:15 INFO - PID 3441 | Values: 230.0 279.0 199.0 197.0 217.0 265.0 267.0 280.0 223.0 199.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#3] audio-fft Cycles:10 Average:68.50 Median:75.50 stddev:12.75 (16.9%) stddev-sans-first:12.69 18:05:15 INFO - PID 3441 | Values: 81.0 58.0 58.0 85.0 60.0 61.0 79.0 49.0 72.0 82.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.50 stddev:9.94 (12.7%) stddev-sans-first:9.10 18:05:15 INFO - PID 3441 | Values: 92.0 71.0 85.0 71.0 69.0 72.0 88.0 69.0 69.0 91.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.00 stddev:3.49 (3.9%) stddev-sans-first:3.64 18:05:15 INFO - PID 3441 | Values: 88.0 88.0 92.0 87.0 89.0 89.0 89.0 88.0 89.0 99.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 18:05:15 INFO - PID 3441 | Values: 95.0 97.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:92.00 stddev:1.52 (1.7%) stddev-sans-first:1.45 18:05:15 INFO - PID 3441 | Values: 90.0 95.0 92.0 92.0 91.0 91.0 92.0 94.0 91.0 91.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#8] json-parse-financial Cycles:10 Average:48.80 Median:49.00 stddev:1.03 (2.1%) stddev-sans-first:1.05 18:05:15 INFO - PID 3441 | Values: 48.0 48.0 48.0 49.0 50.0 48.0 49.0 48.0 51.0 49.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:11.63 (16.9%) stddev-sans-first:11.14 18:05:15 INFO - PID 3441 | Values: 55.0 59.0 62.0 72.0 66.0 62.0 65.0 74.0 87.0 90.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.49 (1.8%) stddev-sans-first:1.58 18:05:15 INFO - PID 3441 | Values: 83.0 82.0 84.0 82.0 84.0 82.0 81.0 86.0 84.0 82.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:142.00 stddev:17.40 (12.3%) stddev-sans-first:17.97 18:05:15 INFO - PID 3441 | Values: 135.0 136.0 134.0 141.0 138.0 139.0 155.0 143.0 192.0 150.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.90 Median:185.00 stddev:7.62 (4.1%) stddev-sans-first:7.34 18:05:15 INFO - PID 3441 | Values: 193.0 186.0 174.0 198.0 183.0 188.0 184.0 179.0 178.0 176.0 18:05:15 INFO - PID 3441 | 18:05:15 INFO - PID 3441 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:7.18 (10.4%) stddev-sans-first:7.57 18:05:15 INFO - PID 3441 | Values: 71.0 69.0 66.0 68.0 68.0 69.0 70.0 64.0 56.0 85.0 18:05:15 INFO - PID 3441 | -------- Summary: end -------- 18:05:15 INFO - PID 3441 | 18:05:16 INFO - TEST-INFO | 3441: exit 0 18:05:16 INFO - TEST-OK | kraken | took 59410ms 18:05:16 INFO - SUITE-END | took 1173s 18:05:16 INFO - Completed test suite (00:19:33) 18:05:16 INFO - javascript benchmark 18:05:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7939.417415662464, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3524.1189702574356, 3548.308079924414, 3573.839177237024, 3582.0, 3607.422812350024, 6112.503480924536, 6117.938820611793, 6222.408871157244, 6229.252489701236, 6233.1585235993125, 5029.371328583927, 5612.382637909826, 6143.204841796983, 6194.7831825886105, 6252.6561039142825, 5343.118385466393, 5425.593080518957, 5483.232347471358, 5501.14732216507, 5540.889182216353, 5373.355197376301, 5482.314710661168, 5523.143912693539, 5530.944690553103, 5549.972250138743, 5502.504774570288, 5535.197396377523, 5544.667319960795, 5571.9442805572035, 5593.720313984305, 5295.735213239361, 5395.7032363220005, 5405.4324295949145, 5474.151506516497, 5474.562035037187, 5307.0, 5346.4386239445075, 5377.542908852753, 5469.945300546983, 5479.835604931858, 6000.069989151645, 6019.217501724747, 6154.5076393888385, 6156.230471191101, 6174.0, 6620.768273110466, 6661.800145995579, 6675.46596272297, 6681.231658359302, 6682.665866706645, 6573.1783527059115, 6624.370684784938, 6758.797236082925, 6775.593464392152, 6799.694013769392, 6211.223597050369, 6258.061290806416, 6312.021636646281, 6313.621182729051, 6325.810225693144, 6839.418649414855, 7014.894776578355, 7019.122609673791, 7030.015797788314, 7035.964820175866, 6355.173827402408, 6574.112494813139, 6594.406503414716, 6623.30455302196, 6664.400203981568, 6620.0, 6729.360710732472, 6732.427743641747, 6763.695633696496, 6820.863582728417, 6419.486441084702, 6502.089707440962, 6684.63234522106, 6687.164104486939, 6703.564268322543, 6575.079488871562, 6595.4393876521035, 6627.701753421107, 6655.800325990228, 6691.966540167268, 5291.576673866081, 5316.787328506816, 5329.493698098674, 5334.413214546396, 5385.596080293915, 4623.953760462352, 4712.976435117803, 4745.0, 4748.4539277982785, 4759.23852183649, 5330.973345133327, 6269.9936020473015, 6288.842778930563, 6310.8422289442215, 6343.714532845941, 6774.288699686561, 6776.085228494188, 6789.25318214996, 6793.422559082434, 6795.524313298021, 6200.131981522591, 6254.280757712831, 6278.277998030194, 6283.02613094971, 6309.621422714652, 5455.863603409867, 5603.3836278009385, 5977.701114944235, 6022.57841951068, 6028.246469191351, 5621.353544342372, 6870.381665650116, 6891.0, 6943.132108486439, 6945.409640180439, 5679.858003549944, 6662.900056499253, 6707.061011458302, 6746.257911629815, 6763.391294783493, 6101.420365065312, 6828.941514065328, 6833.829154271183, 6847.691853866688, 6953.33943275388, 6562.901556476752, 6609.801705948829, 6634.137562116894, 6649.135612370361, 6649.567778094409, 5772.5959182856795, 6473.320301368289, 6731.966340168464, 6766.594004359754, 6769.695363708744, 5261.657992230493, 5353.75908084145, 6321.49428045746, 6584.144061272004, 6603.372679595431, 6490.967545162244, 6577.309382514767, 6589.077529145828, 6597.934020659924, 6611.30581288977, 5247.868803279948, 6273.404026617464, 6410.967945160244, 6443.710033048618, 6471.611703297629, 6348.143000694847, 6539.803805885831, 6659.134312539339, 6665.0, 6667.066610674412, 6216.689165541615, 6357.396047375492, 6395.136656551306, 6457.838554036187, 6474.449671777763], "value": 6071.83975324101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.8997625296838, 811.0915774332747, 845.1590667286051, 1223.198804782867, 1268.3721557828876, 38398.23203535927, 38696.80651596738, 39122.80438597803, 39149.41275880864, 39264.607353926454, 39700.40449393254, 40029.39882404708, 40123.59567415141, 40136.38772510474, 40203.994900127436, 7706.460547761659, 7946.841063178733, 8247.463914845546, 8471.195236452528, 8497.702580409687, 7152.249013853549, 8377.49735015898, 8427.95786021069, 8429.831403371929, 8476.703315383962, 7749.341305988998, 8446.873296900552, 8475.83048339033, 8516.489010659381, 8523.914760852374, 8229.711960081399, 8411.369147313946, 8469.534175620338, 8498.915010849874, 8501.957490212571, 8277.586120694, 8310.8337833243, 8341.499510029356, 8367.163283671645, 8378.952630921134, 36965.63034369649, 36997.81501092477, 37045.81477092624, 37094.07264818374, 37132.62867371319, 3551.751377403583, 3557.3596752584526, 3586.1214002569277, 3587.3901436755814, 3597.118705917041, 30049.849750751324, 30185.698143018508, 30290.84854575713, 30450.238744031354, 30680.53979190314, 3102.937941241185, 3135.0751528299206, 3140.8586613602442, 3146.3707258548384, 3152.7320177784914, 283206.0, 286104.0, 287236.9999999979, 294718.0, 294883.0, 45571.0, 45602.543974560496, 45654.0, 45729.771351143034, 45737.77131114323, 37534.62465375366, 47404.0, 47414.28878566755, 47436.7628161857, 47472.7626361866, 13958.953078519151, 14012.159270443806, 14150.929245353707, 14172.787408188886, 14186.0, 11537.653870383901, 11722.765544689059, 11755.588554400554, 11769.764604707858, 11777.705557360963, 9950.65172718966, 13145.0, 13208.933955330354, 13209.33953302331, 13265.734685306239, 5947.5539334550085, 5975.731092100866, 5978.491828194565, 6000.459958603659, 6020.434686981416, 1015.2943704125562, 1030.7474668706066, 1033.493588141805, 1057.9682609521726, 1061.830107182847, 23165.07339706393, 24318.635220471708, 24450.633240501407, 24480.632790508156, 24551.508969820505, 983.0, 1126.245415571569, 1131.3268605179906, 1156.5084838943415, 1175.7530918507039, 11129.721756956142, 11214.990650841464, 12170.513179472722, 12482.875171248354, 12671.429785659668, 259996.0, 295539.0, 297490.0, 297860.51069744944, 297989.0, 36805.63194368022, 47967.04065918662, 48158.759206203744, 48257.27614085792, 48278.1377489807, 1378.1455497591558, 1382.1548233108913, 1471.0, 1552.2006166824094, 1557.0813220200137, 23183.53632927298, 23972.760272397052, 23986.40033999164, 24148.39629009289, 24204.39489012789, 37012.0, 37076.814615927826, 37094.62905370912, 37119.814400927826, 37168.0, 212184.69538261666, 242174.78912605325, 243637.0, 244545.0, 244745.0, 31986.0, 33462.49806252957, 34358.14104647404, 35800.104997375274, 35989.28021439609, 38693.03267418337, 39227.607723922396, 40599.797001015984, 40775.79612101921, 40793.79603101966, 25729.614055788803, 28621.570676439453, 29896.0, 30347.544786828657, 31212.0, 30443.39113217679, 30893.845530772203, 30963.535546966363, 30968.69031309658, 31437.37125257436, 36199.914002579964, 36820.447693284084, 37160.256794864494, 37397.0, 37499.625003749614, 30309.848450757607, 31035.534466983467, 31106.533401998535, 31116.22209444782, 31206.0, 22611.208607698652, 23760.52478950446, 23867.52264954726, 24275.514489710462, 24328.75671243265], "value": 17136.67562087368, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.9126174765048, 446.5088402756968, 446.5712915601024, 461.8776024353547, 464.0649092079461, 20992.580148397043, 21533.892330538343, 21902.0, 22577.54844903101, 22819.201327953524, 21990.45023874404, 22140.778592214072, 22614.43463913403, 22643.660345094835, 22724.77275227247, 6043.244594425697, 6235.9376406235815, 6237.126802247689, 6460.58006229596, 6776.119104516406, 6296.4018418250425, 6748.291429399916, 6796.422304104157, 6801.829954251159, 6816.693248803791, 6640.634765087918, 6685.231198412196, 6688.030235615833, 6696.129503164606, 6702.162229721285, 4782.521747825207, 6538.390434743518, 6648.135742353512, 6665.033570132328, 6691.832704182386, 6427.164468619097, 6532.444742196919, 6534.967325163344, 6555.180602424697, 6580.14458120446, 28051.896107791996, 28585.714142858513, 28794.856025719946, 29342.559861602094, 29467.263318416997, 2074.3362124120285, 2354.434935615457, 2362.810975122003, 2362.8582285062953, 2364.5152743687504, 18422.53943651406, 18514.0, 18649.0, 19106.522336941547, 19141.458765598767, 2277.0777834976693, 2307.0541078157876, 2374.0978428197345, 2374.4538756086026, 2376.1208352909534, 164133.0, 164364.0, 165544.17227913783, 165774.9999999988, 166033.16983415006, 19815.504612384517, 23006.619602823885, 23060.0, 23446.179383721486, 24496.755032449804, 40120.99697507527, 40563.0, 40690.79654601708, 40767.388489172685, 40777.59222407798, 9536.380135291323, 10346.603208566747, 10785.514651840685, 11165.162612804072, 11294.378809165575, 7517.887231691529, 8672.067770196707, 8758.255548278341, 8796.692115775917, 8966.193042626195, 9150.0, 10738.248322617343, 11311.604093856675, 11364.090872730161, 11461.713457163638, 4109.404136400205, 4113.9098138993195, 4798.04039192156, 4819.566239038433, 4858.983521838398, 800.5836964778283, 919.7056941778567, 980.9803803923882, 983.1839573154265, 1001.2040427859723, 18878.811211887707, 19546.60906781885, 20850.687239691415, 21009.0, 21106.0, 977.2426369563503, 988.8961659025699, 1005.3967619428431, 1007.5466040281756, 1019.8521214423866, 8316.75248712698, 9992.300538962203, 10318.535665895053, 10682.252242343116, 10798.946005269923, 465103.0, 503069.0, 512120.0, 516887.0, 519134.0, 120117.39941300238, 120723.0, 121054.0, 121326.0, 121551.17673234908, 1000.3997601439079, 1155.3241353807948, 1226.999999999982, 1239.3617287097152, 1253.4296894912793, 20855.582888341843, 21745.782542174376, 22002.88998554997, 22168.778312217317, 22176.002079906764, 50450.0, 50682.493175069256, 55220.72389638026, 55346.0, 56593.0, 54359.45640543544, 75677.24322756701, 76660.0, 76891.2310876884, 76921.61539192268, 8663.696770612998, 8742.737717868475, 8750.474971501475, 8781.121887811169, 9136.26909847224, 13685.931570342085, 13723.725525489634, 13868.306584670929, 14003.299835008413, 14078.507252246121, 9809.822821261494, 11787.410629468664, 11832.940835295769, 12002.75994480123, 12112.728163543054, 10914.126869850552, 10929.071028962628, 10939.288946218789, 11238.7752244953, 11338.943305283421, 11871.347075910908, 11906.047516198856, 11917.284962902253, 12037.638870833889, 12164.513419463476, 6643.7674681385915, 7322.780316590511, 8535.634298512208, 8805.515696636747, 8906.287497000094, 8963.955180224057, 9699.0, 11807.409629518661, 11934.761304773681, 12008.0], "value": 11353.604027263538, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.4542801021607, 1457.5408746244939, 1478.3864696151097, 1479.2825479642372, 1487.1597547385727, 10128.341657792238, 10353.689389318313, 11688.298702077882, 11924.344161071149, 12187.756244875098, 11709.297442153476, 11789.0, 11831.0, 11895.821562676567, 11908.702282442944, 2505.674262345897, 2559.11710459892, 2562.987185064072, 2565.615157726337, 2576.8840402181945, 2115.894205289737, 2471.283327834926, 2536.1630661881627, 2540.707818600866, 2557.0794513974956, 2436.5857804173334, 2439.560879041772, 2443.240866576062, 2450.546648869965, 2455.484348286862, 2039.4697378681576, 2520.042383894118, 2523.9747602524108, 2525.83582067166, 2532.607445845897, 2490.1160088168804, 2493.2146373892165, 2495.46347535279, 2503.6119401492797, 2514.1451906351926, 9650.469224192666, 11498.310101393943, 11779.7055073623, 11822.408879555987, 11840.46717897688, 5667.943320566783, 6515.804525864231, 6749.392554670103, 6758.729650813962, 6764.458843292525, 4026.71812973092, 4265.466816647919, 4282.593153650399, 4313.84901528445, 4361.302191649321, 1224.5897624295872, 1252.1235135405252, 1295.2099219476113, 1299.5646458436436, 1305.1777380250383, 16386.426475073316, 16472.505824825155, 16481.25834337458, 16484.175791210393, 16500.092494912777, 4869.9269510957365, 5778.451047150514, 6071.878562428815, 6176.969115154396, 6190.907136392958, 13804.585862424143, 13804.723905521832, 13806.17162970225, 13826.23955682427, 13835.239061851493, 6653.334666533405, 6766.69549870257, 6779.864402711918, 6787.898181527281, 6790.675818215499, 5918.8816223675285, 6180.47465965398, 6209.844753881099, 6247.625142491465, 6252.499800015988, 3645.8177091145335, 4120.793960301973, 4292.334688123346, 4340.848070317524, 4373.825046998148, 1287.1569122224957, 1287.9034072444604, 1305.4386613755987, 1306.8627794081674, 1316.5457917018614, 741.2476336518438, 788.4244501513926, 797.6928882380325, 817.9018517777905, 827.2182787266096, 5207.479252074838, 5666.291713535808, 6250.999840025665, 6984.650767461607, 7052.294770523009, 821.4291067708006, 948.6395169835556, 963.4363897120123, 967.7435479597913, 994.6021591363512, 3496.5629296337956, 4238.724482908601, 4275.593818587229, 4343.218220720238, 4472.39622650938, 14466.05970611907, 14799.112053276838, 14817.40730370773, 14875.851241487662, 14930.25348732559, 14113.85886141146, 14694.706105878036, 14769.187694676895, 14784.852151478563, 14803.185824779524, 7264.818379540554, 8181.468204566684, 8205.343572514184, 8528.872066918877, 8580.828383432421, 6521.413072823469, 6674.866502669823, 6718.93281067183, 6817.897731533932, 6843.623600702009, 10918.563257469612, 13159.144655597356, 13165.683431656758, 13264.734705305647, 13434.462621495031, 13340.19958802475, 13529.188248704715, 13532.93233533826, 13616.319184040956, 13871.514496992557, 8070.959645201737, 8579.313654907717, 8842.867356989778, 9003.729888103368, 9213.631454741735, 10954.945225274141, 11116.555337786398, 11120.833187502032, 11122.165837562054, 11137.0, 8970.551472426483, 9201.263898887939, 9237.03011183816, 9309.627614895327, 9456.432614043179, 8981.910180898107, 9036.45781253127, 9048.321375896965, 9061.456312621263, 9077.500737459502, 8095.15000924918, 8127.065387480516, 8209.05595856484, 8214.014318281845, 8216.46592971455, 4608.608268297098, 5748.770049198152, 5753.0, 6005.3694362091355, 6115.449609535163, 5958.285005799332, 6006.48944839676, 6028.125921741411, 6216.53375996793, 6299.0], "value": 5373.679006110817, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.84497286275456, 405.92490389277987, 408.21418768869955, 413.9730917490361, 415.57818813903884, 5691.0, 5804.361520232771, 6087.208662873832, 6154.0, 6323.336049714782, 5948.672822994734, 6008.858316919782, 6206.1931948846695, 6222.875542489159, 6234.937650623492, 3239.6274428440797, 3638.890833274993, 4078.8572399966006, 4461.4869290031575, 4492.955070449287, 3982.7255278310954, 4211.915761684764, 4492.0791237796175, 4495.1459222747735, 4540.636749060084, 3846.4614953906475, 4457.375967364572, 4461.531539188387, 4462.995825939154, 4465.5311192324825, 4300.290452075404, 4419.315006174048, 4470.575295346936, 4478.97760511197, 4493.618042466394, 3810.6951443884423, 3815.8282877270312, 3816.072883193997, 3819.7135214858718, 3828.3300422425964, 5996.580239383245, 6023.066424704178, 6028.278469506513, 6039.161594050789, 6043.4975942922365, 5223.791048358061, 5303.806643505181, 5348.973255133738, 5371.516563509303, 5396.811111611075, 5424.321959755031, 5889.8822023559715, 6007.008843540854, 6617.139771829631, 6640.966795166041, 3951.881443556698, 4213.9578604213875, 4364.432623758923, 4369.912601747979, 4386.8903277418, 5337.599680024014, 5996.820095397145, 6104.633721976696, 6826.795196144124, 7002.3697867192195, 3836.4437156612134, 4312.784360781949, 4416.183006143874, 4423.788105947026, 4427.114577084596, 3774.6036666150208, 4495.550444955544, 4500.347449619852, 4518.322251662277, 4527.796249168795, 5162.483751624807, 5217.765200565984, 5284.7093409862055, 5379.246905433242, 5386.407495175527, 6696.06255124283, 6710.127683401127, 6715.1941766988275, 6742.764003259862, 6758.628275444898, 1254.3728135932033, 1403.614006148297, 1413.7172565486944, 1544.343653947068, 1549.9922500387427, 847.656699036891, 979.2361957672885, 998.2912132385942, 998.5756053677159, 1015.6851376073441, 6318.589291696025, 6329.208848893888, 6330.3669633036325, 6336.0, 6351.777687780906, 6668.766593169215, 6885.414739747178, 6891.657546090142, 6894.794430205163, 6933.54931929433, 5254.605904557174, 5461.890762184734, 6154.5076393888385, 6242.406971337716, 6291.049774569898, 3187.059817353886, 3403.7957722536726, 3528.364894318996, 3564.287142571496, 3611.8916432507067, 5832.358440571534, 6397.296297407282, 6438.7102580384, 6490.610563366213, 7119.964400177966, 6504.252011018793, 7096.53872498286, 7128.287171282934, 7131.429485641136, 7145.606991615511, 5928.436798504135, 6481.351864813575, 6778.389944904982, 7183.85058390655, 7261.092363454568, 6121.908171377522, 6900.413464855544, 7004.649767511706, 7028.367446929597, 7049.577025378494, 5592.916106258491, 6119.816405507842, 6523.869522609426, 6678.532502724763, 6885.173779146535, 5861.706914654335, 6080.331163572092, 7049.859002820018, 7072.823179420556, 7182.425405967615, 3683.9079023024638, 4146.398772178078, 4435.778211089368, 4476.060027394284, 4495.0, 5271.736413179402, 5401.918971215514, 5718.456746609066, 6813.488988325995, 7033.648317583998, 6241.500679945684, 6615.04081908111, 6715.5970641759695, 6736.867356679168, 6738.09035780183, 5533.6956467393065, 6153.29237137717, 6614.338566143347, 6744.763933262312, 6773.593584384953, 5247.632665713363, 5370.677759334452, 6515.990021546668, 6643.0, 6657.868162412305, 5692.914606280826, 6030.849228769316, 6255.499560035095, 6779.661016949034, 6784.6946887389195, 6141.477974372228, 6473.838154045998, 6535.411812936859, 6546.509011824037, 6557.114869457423], "value": 4721.551900774822, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.7068740597092, 835.7659855240532, 855.4696088425173, 857.2242120880601, 871.2594294849379, 15643.92178039111, 16149.43476978306, 16574.83425165748, 16609.418670346542, 18361.0, 14960.0, 16345.427910023152, 17759.378421755242, 17883.0, 18231.17959691817, 990.1732053735121, 1004.9899501004969, 1009.9747506312327, 1017.0592202212961, 1023.6673081248587, 1009.5759780892043, 1020.1787561013381, 1027.7841653252788, 1032.9876720813606, 1038.8493668418075, 1069.059227879465, 1080.6595922284494, 1089.0525243038567, 1089.8855620159889, 1095.5343978809015, 1081.4214395298477, 1082.0910435234362, 1085.4301491716833, 1088.7659153282082, 1094.370736826324, 787.5904529644553, 810.2828996338225, 812.8130529978138, 812.9918700812975, 813.1096449387911, 16539.25573349202, 16847.0, 17075.914620426818, 17282.395116171, 17289.48131556055, 16739.246733897005, 16778.0, 17140.65718685632, 17172.484825455256, 17250.0512471814, 13082.672933176651, 13219.272939988297, 13348.132371395925, 13457.865421345858, 13622.795658065137, 3501.5973163086314, 3530.2409981853766, 3538.097785064818, 3551.1122219445137, 3596.730245231618, 128823.35588322092, 128868.0, 129091.89994550296, 129484.35257823745, 131371.34314328464, 11338.489767960462, 12922.870771292355, 13166.670833229246, 13361.0, 13376.929845612327, 21170.47073823136, 25220.369490762878, 25853.35366615812, 25938.481230375288, 25978.090766823072, 6467.967660161669, 7938.166492518318, 7973.641186146638, 7975.0, 7998.360131189491, 3991.1419044905483, 4007.238624661305, 4036.273470775289, 4064.837406503707, 4068.613481719232, 4440.267355886307, 4514.616257618073, 4518.819247230074, 4523.389342438794, 4524.773761311987, 917.8393781088283, 952.3428834104584, 959.491469521155, 967.7725734452398, 968.7868668892833, 658.8649326887977, 659.7031335898864, 674.3458844920419, 678.176016140382, 683.1597135523235, 10676.786464270672, 11613.245139065933, 11976.20356946465, 11986.460609272604, 12028.63914082579, 636.5003472274335, 642.328766439063, 650.72344253692, 650.9902351464732, 673.397309408077, 5319.0, 5540.0, 5591.776328946797, 5595.272614560163, 6039.124326972652, 85894.14105858987, 87493.56253218821, 89371.0, 97316.51341743246, 98548.0, 80658.59670701726, 83803.16196838075, 89377.21245575052, 90356.54821725849, 91675.54162229146, 69986.30013699798, 70963.2903670967, 70988.0, 71556.284437156, 71794.0, 12142.664306926408, 12225.388730563258, 12267.509299627915, 12288.262704237774, 12462.750744985231, 39964.60035399609, 40275.99310017156, 40657.1868562633, 41057.58942410657, 41298.0, 51399.74300128625, 51855.740721296155, 52225.0, 52345.21482177846, 52375.0, 16172.110533920748, 16281.51155465338, 16623.667526649642, 16807.243674034453, 16858.57853553622, 17796.0, 18013.369532066314, 18542.81457185411, 18622.813771862107, 18722.438326850217, 15026.24868756536, 15537.06777593348, 16388.344466221693, 17139.228734706718, 17181.484555463358, 13301.26843023643, 16578.17109144562, 16837.0, 16901.32394704198, 17065.829341706423, 15837.920810395874, 16535.511803937705, 16722.498325050266, 16745.581360466087, 16901.0, 11663.59177428786, 11724.120690948042, 11958.64124076279, 12117.75764484723, 12341.691457713629, 10794.406307653155, 13337.599872003855, 13498.52755153565, 13514.864851351755, 13785.931070344985], "value": 8355.31869470521, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1493.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 116.0, 115.0, 114.0, 115.0, 113.0, 114.0, 113.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 110.0, 146.0, 111.0, 111.0, 112.0, 109.0, 126.0, 135.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [230.0, 279.0, 199.0, 197.0, 217.0, 265.0, 267.0, 280.0, 223.0, 199.0], "value": 235.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 58.0, 85.0, 60.0, 61.0, 79.0, 49.0, 72.0, 82.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 85.0, 71.0, 69.0, 72.0, 88.0, 69.0, 69.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 87.0, 89.0, 89.0, 89.0, 88.0, 89.0, 99.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 92.0, 92.0, 91.0, 91.0, 92.0, 94.0, 91.0, 91.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 48.0, 49.0, 50.0, 48.0, 49.0, 48.0, 51.0, 49.0], "value": 48.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 62.0, 72.0, 66.0, 62.0, 65.0, 74.0, 87.0, 90.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 84.0, 82.0, 84.0, 82.0, 81.0, 86.0, 84.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 134.0, 141.0, 138.0, 139.0, 155.0, 143.0, 192.0, 150.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 186.0, 174.0, 198.0, 183.0, 188.0, 184.0, 179.0, 178.0, 176.0], "value": 183.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 66.0, 68.0, 68.0, 69.0, 70.0, 64.0, 56.0, 85.0], "value": 68.6, "unit": "score"}]}]} 18:05:17 INFO - Return code: 0 18:05:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:05:17 INFO - # TBPL SUCCESS # 18:05:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:17 INFO - 2017-05-15 18:05:17.080 screenresolution[3448:69621] starting screenresolution argv=screenresolution get 18:05:17 INFO - 2017-05-15 18:05:17.084 screenresolution[3448:69621] Display 0: 1600x1200x32@60 18:05:17 INFO - 2017-05-15 18:05:17.091 screenresolution[3449:69623] starting screenresolution argv=screenresolution list 18:05:17 INFO - Available Modes on Display 0 18:05:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:17 INFO - Intel Iris: 18:05:17 INFO - Chipset Model: Intel Iris 18:05:17 INFO - Type: GPU 18:05:17 INFO - Bus: Built-In 18:05:17 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:17 INFO - Vendor: Intel (0x8086) 18:05:17 INFO - Device ID: 0x0a2e 18:05:17 INFO - Revision ID: 0x0009 18:05:17 INFO - Displays: 18:05:17 INFO - Display: 18:05:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:17 INFO - Display Serial Number: 1600x1200 60 18:05:17 INFO - Main Display: Yes 18:05:17 INFO - Mirror: Off 18:05:17 INFO - Online: Yes 18:05:17 INFO - Rotation: Supported 18:05:17 INFO - Return code: 0 18:05:17 INFO - Running post-action listener: _resource_record_post_action 18:05:17 INFO - [mozharness: 2017-05-16 01:05:17.335408Z] Finished run-tests step (success) 18:05:17 INFO - Running post-run listener: _resource_record_post_run 18:05:17 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 1876480; Write bytes: 534056960; Read time: 16; Write time: 1652 18:05:17 INFO - TinderboxPrint: CPU usage
54.2% 18:05:17 INFO - TinderboxPrint: I/O read bytes / time
1,876,480 / 16 18:05:17 INFO - TinderboxPrint: I/O write bytes / time
534,056,960 / 1,652 18:05:17 INFO - TinderboxPrint: CPU idle
2,192.2 (45.8%) 18:05:17 INFO - TinderboxPrint: CPU system
964.4 (20.2%) 18:05:17 INFO - TinderboxPrint: CPU user
1,625.0 (34.0%) 18:05:17 INFO - TinderboxPrint: Swap in / out
215,805,952 / 0 18:05:17 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206604800; Write bytes: 209725440; Read time: 15775; Write time: 437 18:05:17 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 1875968; Write bytes: 314484736; Read time: 16; Write time: 1182 18:05:17 INFO - Running post-run listener: _upload_blobber_files 18:05:17 INFO - Blob upload gear active. 18:05:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:05:17 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 18:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:05:18 INFO - (blobuploader) - INFO - Open directory for files ... 18:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:05:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:19 INFO - (blobuploader) - INFO - Done attempting. 18:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:20 INFO - (blobuploader) - INFO - Done attempting. 18:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:22 INFO - (blobuploader) - INFO - Done attempting. 18:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:24 INFO - (blobuploader) - INFO - Done attempting. 18:05:24 INFO - (blobuploader) - INFO - Iteration through files over. 18:05:24 INFO - Return code: 0 18:05:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:05:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:05:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32ff9c242a9f6f3c301356294200c60a2ba01ec4ee114bfdac0e97ffb46dd007f13050c493c291494b6281339ce46aa55ce96b4553b0d0489660769b11ff91ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06aea74f4b912e4cca8d2a62555848aee2990045369de8010b18fc26c160835f31fe0900fe10ca23fe9ddae13033ea02efaa0740357be6f08b694305113500ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7168712edab1ad7bcf931f8b88288162dd8e436bbaf130e367cfd9cca1a761054d36e88aebb3faecc094bb488602e7a5ec9227c5a544bbd131e9be4ec13aba89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10015e34b6dc447d08180933d0233a278b40f2d9f0526019ff69a386432518d9827ce941a85bf1fec4ab245d3ed7e13e7cb9e8bdae07e5d802e849d572b60b6e"} 18:05:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:05:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:05:24 INFO - Contents: 18:05:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32ff9c242a9f6f3c301356294200c60a2ba01ec4ee114bfdac0e97ffb46dd007f13050c493c291494b6281339ce46aa55ce96b4553b0d0489660769b11ff91ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06aea74f4b912e4cca8d2a62555848aee2990045369de8010b18fc26c160835f31fe0900fe10ca23fe9ddae13033ea02efaa0740357be6f08b694305113500ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7168712edab1ad7bcf931f8b88288162dd8e436bbaf130e367cfd9cca1a761054d36e88aebb3faecc094bb488602e7a5ec9227c5a544bbd131e9be4ec13aba89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10015e34b6dc447d08180933d0233a278b40f2d9f0526019ff69a386432518d9827ce941a85bf1fec4ab245d3ed7e13e7cb9e8bdae07e5d802e849d572b60b6e"} 18:05:24 INFO - Running post-run listener: copy_logs_to_upload_dir 18:05:24 INFO - Copying logs to upload dir... 18:05:24 INFO - mkdir: /builds/slave/test/build/upload/logs 18:05:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.721796 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-15 18:05:24.790870) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 18:05:24.794948) ========= 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.ffvuOrkNvL/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.od8xTFy1pV/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32ff9c242a9f6f3c301356294200c60a2ba01ec4ee114bfdac0e97ffb46dd007f13050c493c291494b6281339ce46aa55ce96b4553b0d0489660769b11ff91ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06aea74f4b912e4cca8d2a62555848aee2990045369de8010b18fc26c160835f31fe0900fe10ca23fe9ddae13033ea02efaa0740357be6f08b694305113500ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7168712edab1ad7bcf931f8b88288162dd8e436bbaf130e367cfd9cca1a761054d36e88aebb3faecc094bb488602e7a5ec9227c5a544bbd131e9be4ec13aba89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10015e34b6dc447d08180933d0233a278b40f2d9f0526019ff69a386432518d9827ce941a85bf1fec4ab245d3ed7e13e7cb9e8bdae07e5d802e849d572b60b6e"} build_url:https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/G2y7wgxYSROQkCgHHi-C4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32ff9c242a9f6f3c301356294200c60a2ba01ec4ee114bfdac0e97ffb46dd007f13050c493c291494b6281339ce46aa55ce96b4553b0d0489660769b11ff91ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06aea74f4b912e4cca8d2a62555848aee2990045369de8010b18fc26c160835f31fe0900fe10ca23fe9ddae13033ea02efaa0740357be6f08b694305113500ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7168712edab1ad7bcf931f8b88288162dd8e436bbaf130e367cfd9cca1a761054d36e88aebb3faecc094bb488602e7a5ec9227c5a544bbd131e9be4ec13aba89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10015e34b6dc447d08180933d0233a278b40f2d9f0526019ff69a386432518d9827ce941a85bf1fec4ab245d3ed7e13e7cb9e8bdae07e5d802e849d572b60b6e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 18:05:24.829176) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 18:05:24.829544) ========= 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.ffvuOrkNvL/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.od8xTFy1pV/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005154 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 18:05:24.878539) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 18:05:24.878877) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 18:05:24.879242) ========= ========= Total master_lag: 0.18 =========