builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0247 starttime: 1495767363.07 results: success (0) revision: c2ff59dd31bce41bc9108939e86618017943b88d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.066186) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.066704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.067012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.093071) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.093474) ========= 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.FklG4k30dl/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.tKoahAvQ5Z/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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-25 19:56:03-- 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% 179M=0s 2017-05-25 19:56:03 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.186152 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.300753) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.301080) ========= 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.FklG4k30dl/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.tKoahAvQ5Z/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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.026595 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:03.350050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 19:56:03.350393) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c2ff59dd31bce41bc9108939e86618017943b88d --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 c2ff59dd31bce41bc9108939e86618017943b88d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FklG4k30dl/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.tKoahAvQ5Z/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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-25 19:56:03,449 truncating revision to first 12 chars 2017-05-25 19:56:03,449 Setting DEBUG logging. 2017-05-25 19:56:03,449 attempt 1/10 2017-05-25 19:56:03,449 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c2ff59dd31bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 19:56:05,100 unpacking tar archive at: mozilla-central-c2ff59dd31bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.941919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 19:56:05.315954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:05.316426) ========= script_repo_revision: c2ff59dd31bce41bc9108939e86618017943b88d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:05.317059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:05.317470) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 19:56:05.337610) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-25 19:56:05.338049) ========= /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.FklG4k30dl/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.tKoahAvQ5Z/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:56:05 INFO - MultiFileLogger online at 20170525 19:56:05 in /builds/slave/test 19:56:05 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 19:56:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:56:05 INFO - {'append_to_log': False, 19:56:05 INFO - 'base_work_dir': '/builds/slave/test', 19:56:05 INFO - 'blob_upload_branch': 'Firefox', 19:56:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:56:05 INFO - 'branch': 'Firefox', 19:56:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:56:05 INFO - 'config_files': ('talos/mac_config.py',), 19:56:05 INFO - 'default_actions': ('clobber', 19:56:05 INFO - 'read-buildbot-config', 19:56:05 INFO - 'download-and-extract', 19:56:05 INFO - 'populate-webroot', 19:56:05 INFO - 'create-virtualenv', 19:56:05 INFO - 'install', 19:56:05 INFO - 'run-tests'), 19:56:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:56:05 INFO - 'download_minidump_stackwalk': True, 19:56:05 INFO - 'download_symbols': 'ondemand', 19:56:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:56:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:56:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:56:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:56:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:56:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:56:05 INFO - 'gecko_profile': False, 19:56:05 INFO - 'gecko_profile_interval': 0, 19:56:05 INFO - 'installer_path': 'installer.exe', 19:56:05 INFO - 'log_level': 'info', 19:56:05 INFO - 'log_name': 'talos', 19:56:05 INFO - 'log_to_console': True, 19:56:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:56:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:56:05 INFO - 'opt_config_files': (), 19:56:05 INFO - 'pip_index': False, 19:56:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:56:05 INFO - 'cmd': ('bash', 19:56:05 INFO - '-c', 19:56:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:56:05 INFO - 'enabled': True, 19:56:05 INFO - 'halt_on_failure': False, 19:56:05 INFO - 'name': 'check_screen_resolution'},), 19:56:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:56:05 INFO - 'cmd': ('bash', 19:56:05 INFO - '-c', 19:56:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:56:05 INFO - 'enabled': True, 19:56:05 INFO - 'halt_on_failure': False, 19:56:05 INFO - 'name': 'check_screen_resolution'},), 19:56:05 INFO - 'run_cmd_checks_enabled': True, 19:56:05 INFO - 'suite': 'dromaeojs-e10s', 19:56:05 INFO - 'system_bits': '32', 19:56:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:56:05 INFO - 'title': 't-yosemite-r7-0247', 19:56:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:56:05 INFO - 'use_talos_json': True, 19:56:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:56:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:56:05 INFO - 'work_dir': 'build'} 19:56:05 INFO - [mozharness: 2017-05-26 02:56:05.545155Z] Running clobber step. 19:56:05 INFO - Running pre-action listener: _resource_record_pre_action 19:56:05 INFO - Running main action method: clobber 19:56:05 INFO - rmtree: /builds/slave/test/build 19:56:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:56:08 INFO - Running post-action listener: _resource_record_post_action 19:56:08 INFO - [mozharness: 2017-05-26 02:56:08.223712Z] Finished clobber step (success) 19:56:08 INFO - [mozharness: 2017-05-26 02:56:08.223838Z] Running read-buildbot-config step. 19:56:08 INFO - Running pre-action listener: _resource_record_pre_action 19:56:08 INFO - Running main action method: read_buildbot_config 19:56:08 INFO - Using buildbot properties: 19:56:08 INFO - { 19:56:08 INFO - "project": "", 19:56:08 INFO - "product": "firefox", 19:56:08 INFO - "who": "ryanvm@gmail.com", 19:56:08 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 19:56:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:56:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:56:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:56:08 INFO - "stage_platform": "macosx64", 19:56:08 INFO - "basedir": "/builds/slave/test", 19:56:08 INFO - "buildnumber": 64, 19:56:08 INFO - "platform": "yosemite_r7", 19:56:08 INFO - "parent_task_id": "E1QJntZ_S7ePk9jyz8ku_g", 19:56:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:56:08 INFO - "slavebuilddir": "test", 19:56:08 INFO - "taskId": "JjtL0yAwSTear0dlrBcYKg", 19:56:08 INFO - "branch": "mozilla-central", 19:56:08 INFO - "script_repo_revision": "production", 19:56:08 INFO - "revision": "c2ff59dd31bce41bc9108939e86618017943b88d", 19:56:08 INFO - "slavename": "t-yosemite-r7-0247", 19:56:08 INFO - "repo_path": "mozilla-central" 19:56:08 INFO - } 19:56:08 INFO - Finding installer, test and symbols from parent task. 19:56:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JjtL0yAwSTear0dlrBcYKg'}, attempt #1 19:56:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JjtL0yAwSTear0dlrBcYKg'}, attempt #1 19:56:09 INFO - Task dependencies: HB3u-4ErR06Op9b8xDI3Jw E1QJntZ_S7ePk9jyz8ku_g 19:56:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g'}, attempt #1 19:56:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:56:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/test_packages.json 19:56:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/target.crashreporter-symbols.zip 19:56:09 INFO - Running post-action listener: _resource_record_post_action 19:56:09 INFO - [mozharness: 2017-05-26 02:56:09.939308Z] Finished read-buildbot-config step (success) 19:56:09 INFO - [mozharness: 2017-05-26 02:56:09.939425Z] Running download-and-extract step. 19:56:09 INFO - Running pre-action listener: _resource_record_pre_action 19:56:09 INFO - Running main action method: download_and_extract 19:56:09 INFO - mkdir: /builds/slave/test/build/tests 19:56: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')]} 19:56:09 INFO - trying https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:56:09 INFO - Downloading https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/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 19:56:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/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 19:56:10 INFO - Downloaded 1325 bytes. 19:56:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:56:10 INFO - Using the following test package requirements: 19:56:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:56:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:56:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:56:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:56:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'jsshell-mac.zip'], 19:56:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:56:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:56:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:56:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:56:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:56:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:56:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:56:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:56:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:56:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:56:10 INFO - Fetch https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:56:12 INFO - Content-Length response header: 57627063 19:56:12 INFO - Bytes received: 57627063 19:56:30 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 19:56:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:56:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:56:30 INFO - Fetch https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:56:32 INFO - Content-Length response header: 57627063 19:56:32 INFO - Bytes received: 57627063 19:56:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:56:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:56:49 INFO - Fetch https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:56:51 INFO - Content-Length response header: 14411888 19:56:51 INFO - Bytes received: 14411888 19:56:51 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')]} 19:56:51 INFO - trying https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:56:51 INFO - Downloading https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:56:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/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 19:56:53 INFO - Downloaded 67224217 bytes. 19:56:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:56:53 INFO - mkdir: /builds/slave/test/properties 19:56:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:56:53 INFO - Writing to file /builds/slave/test/properties/build_url 19:56:53 INFO - Contents: 19:56:53 INFO - build_url:https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:56:53 INFO - Running post-action listener: _resource_record_post_action 19:56:53 INFO - Running post-action listener: set_extra_try_arguments 19:56:53 INFO - [mozharness: 2017-05-26 02:56:53.530431Z] Finished download-and-extract step (success) 19:56:53 INFO - [mozharness: 2017-05-26 02:56:53.530566Z] Running populate-webroot step. 19:56:53 INFO - Running pre-action listener: _resource_record_pre_action 19:56:53 INFO - Running main action method: populate_webroot 19:56:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:56:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:56:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:56:53 INFO - '--tppagecycles', 19:56:53 INFO - '7'], 19:56:53 INFO - 'tests': ['tsvgm']}}, 19:56:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:56:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:56:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:56:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:56:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:56:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:56:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['damp', 'tps']}, 19:56:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'tests': ['damp', 'tps']}, 19:56:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['dromaeo_dom']}, 19:56:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:56:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:56:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:56:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:56:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['a11yr', 19:56:53 INFO - 'ts_paint', 19:56:53 INFO - 'tpaint', 19:56:53 INFO - 'sessionrestore', 19:56:53 INFO - 'sessionrestore_no_auto_restore', 19:56:53 INFO - 'tabpaint']}, 19:56:53 INFO - 'other-e10s': {'tests': ['a11yr', 19:56:53 INFO - 'ts_paint', 19:56:53 INFO - 'tpaint', 19:56:53 INFO - 'sessionrestore', 19:56:53 INFO - 'sessionrestore_no_auto_restore', 19:56:53 INFO - 'tabpaint']}, 19:56:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:56:53 INFO - 'ts_paint', 19:56:53 INFO - 'tpaint', 19:56:53 INFO - 'sessionrestore', 19:56:53 INFO - 'sessionrestore_no_auto_restore', 19:56:53 INFO - 'tabpaint']}, 19:56:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:56:53 INFO - 'ts_paint', 19:56:53 INFO - 'tpaint', 19:56:53 INFO - 'sessionrestore', 19:56:53 INFO - 'sessionrestore_no_auto_restore', 19:56:53 INFO - 'tabpaint']}, 19:56:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['a11yr', 19:56:53 INFO - 'ts_paint', 19:56:53 INFO - 'tpaint', 19:56:53 INFO - 'sessionrestore', 19:56:53 INFO - 'sessionrestore_no_auto_restore', 19:56:53 INFO - 'tabpaint']}, 19:56:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['a11yr', 19:56:53 INFO - 'ts_paint', 19:56:53 INFO - 'tpaint', 19:56:53 INFO - 'sessionrestore', 19:56:53 INFO - 'sessionrestore_no_auto_restore', 19:56:53 INFO - 'tabpaint']}, 19:56:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:56:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:56:53 INFO - 'bloom_basic_ref']}, 19:56:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['tsvgx', 19:56:53 INFO - 'tsvgr_opacity', 19:56:53 INFO - 'tart', 19:56:53 INFO - 'tscrollx', 19:56:53 INFO - 'cart', 19:56:53 INFO - 'tsvg_static']}, 19:56:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:56:53 INFO - 'tsvgr_opacity', 19:56:53 INFO - 'tart', 19:56:53 INFO - 'tscrollx', 19:56:53 INFO - 'cart', 19:56:53 INFO - 'tsvg_static']}, 19:56:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'talos_options': ['--disable-e10s'], 19:56:53 INFO - 'tests': ['tp5o']}, 19:56:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:56:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'talos_options': ['--disable-e10s', 19:56:53 INFO - '--xperf_path', 19:56:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:56:53 INFO - 'tests': ['tp5n']}, 19:56:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:56:53 INFO - 'talos_options': ['--xperf_path', 19:56:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:56:53 INFO - 'tests': ['tp5n']}}, 19:56:53 INFO - 'talos.zip': {'path': '', 19:56:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:56:53 INFO - Running post-action listener: _resource_record_post_action 19:56:53 INFO - [mozharness: 2017-05-26 02:56:53.552268Z] Finished populate-webroot step (success) 19:56:53 INFO - [mozharness: 2017-05-26 02:56:53.552422Z] Running create-virtualenv step. 19:56:53 INFO - Running pre-action listener: _resource_record_pre_action 19:56:53 INFO - Running main action method: create_virtualenv 19:56:53 INFO - Creating virtualenv /builds/slave/test/build/venv 19:56:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:56:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:56:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:56:53 INFO - Using real prefix '/tools/python27' 19:56:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:56:54 INFO - Installing distribute.............................................................................................................................................................................................done. 19:56:57 INFO - Installing pip.................done. 19:56:57 INFO - Return code: 0 19:56:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:56:57 INFO - Reading from file tmpfile_stdout 19:56:57 INFO - Output received: 19:56:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:56:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:56:57 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')]} 19:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:57 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')]} 19:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:56:57 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 19:56:57 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 19:56:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:56:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:57 INFO - 'HOME': '/Users/cltbld', 19:56:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:57 INFO - 'LOGNAME': 'cltbld', 19:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:57 INFO - 'MOZ_NO_REMOTE': '1', 19:56:57 INFO - 'NO_EM_RESTART': '1', 19:56:57 INFO - 'PAGER': '/bin/cat', 19:56:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:57 INFO - 'PWD': '/builds/slave/test', 19:56:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:57 INFO - 'SHELL': '/bin/bash', 19:56:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:56:57 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:56:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:57 INFO - 'USER': 'cltbld', 19:56:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:57 INFO - 'XPC_FLAGS': '0x0', 19:56:57 INFO - 'XPC_SERVICE_NAME': '0', 19:56:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:58 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 19:56:58 INFO - Cleaning up... 19:56:58 INFO - Return code: 0 19:56:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:56:58 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')]} 19:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:58 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')]} 19:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:58 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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:56:58 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 19:56:58 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 19:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:58 INFO - 'HOME': '/Users/cltbld', 19:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:58 INFO - 'LOGNAME': 'cltbld', 19:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:58 INFO - 'MOZ_NO_REMOTE': '1', 19:56:58 INFO - 'NO_EM_RESTART': '1', 19:56:58 INFO - 'PAGER': '/bin/cat', 19:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:58 INFO - 'PWD': '/builds/slave/test', 19:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:58 INFO - 'SHELL': '/bin/bash', 19:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:56:58 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:58 INFO - 'USER': 'cltbld', 19:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:58 INFO - 'XPC_FLAGS': '0x0', 19:56:58 INFO - 'XPC_SERVICE_NAME': '0', 19:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:58 INFO - Downloading/unpacking psutil>=3.1.1 19:56:58 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 19:56:58 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 19:56:58 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 19:56:58 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 19:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:57:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:57:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:57:00 INFO - Installing collected packages: psutil 19:57:00 INFO - Running setup.py install for psutil 19:57:01 INFO - building 'psutil._psutil_osx' extension 19:57:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:57:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:57:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:57:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:57:01 INFO - building 'psutil._psutil_posix' extension 19:57:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:57:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:57:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:57:01 INFO - ^ 19:57:01 INFO - 1 warning generated. 19:57:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:57:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:57:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:57:02 INFO - Successfully installed psutil 19:57:02 INFO - Cleaning up... 19:57:02 INFO - Return code: 0 19:57:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:57:02 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')]} 19:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57:02 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')]} 19:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57:02 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 19:57:02 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 19:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:02 INFO - 'HOME': '/Users/cltbld', 19:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:02 INFO - 'LOGNAME': 'cltbld', 19:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:02 INFO - 'MOZ_NO_REMOTE': '1', 19:57:02 INFO - 'NO_EM_RESTART': '1', 19:57:02 INFO - 'PAGER': '/bin/cat', 19:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:02 INFO - 'PWD': '/builds/slave/test', 19:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:02 INFO - 'SHELL': '/bin/bash', 19:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:02 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:02 INFO - 'USER': 'cltbld', 19:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:02 INFO - 'XPC_FLAGS': '0x0', 19:57:02 INFO - 'XPC_SERVICE_NAME': '0', 19:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:57:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:57:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:57:03 INFO - Installing collected packages: mozsystemmonitor 19:57:03 INFO - Running setup.py install for mozsystemmonitor 19:57:03 INFO - Successfully installed mozsystemmonitor 19:57:03 INFO - Cleaning up... 19:57:04 INFO - Return code: 0 19:57:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:57: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')]} 19:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57: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')]} 19:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57: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', '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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:57: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 jsonschema==2.5.1 19:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:04 INFO - 'HOME': '/Users/cltbld', 19:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:04 INFO - 'LOGNAME': 'cltbld', 19:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:04 INFO - 'MOZ_NO_REMOTE': '1', 19:57:04 INFO - 'NO_EM_RESTART': '1', 19:57:04 INFO - 'PAGER': '/bin/cat', 19:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:04 INFO - 'PWD': '/builds/slave/test', 19:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:04 INFO - 'SHELL': '/bin/bash', 19:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:04 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:04 INFO - 'USER': 'cltbld', 19:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:04 INFO - 'XPC_FLAGS': '0x0', 19:57:04 INFO - 'XPC_SERVICE_NAME': '0', 19:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:04 INFO - Downloading/unpacking jsonschema==2.5.1 19:57: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 19:57: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 19:57: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 19:57: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 19:57:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:57:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:57:05 INFO - Downloading functools32-3.2.3-2.tar.gz 19:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:57:06 INFO - warning: no files found matching '*.txt' 19:57:06 INFO - no previously-included directories found matching 'build' 19:57:06 INFO - no previously-included directories found matching 'dist' 19:57:06 INFO - no previously-included directories found matching '.git*' 19:57:06 INFO - Installing collected packages: jsonschema, functools32 19:57:06 INFO - Running setup.py install for functools32 19:57:06 INFO - warning: no files found matching '*.txt' 19:57:06 INFO - no previously-included directories found matching 'build' 19:57:06 INFO - no previously-included directories found matching 'dist' 19:57:06 INFO - no previously-included directories found matching '.git*' 19:57:06 INFO - Successfully installed jsonschema functools32 19:57:06 INFO - Cleaning up... 19:57:06 INFO - Return code: 0 19:57:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:57: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')]} 19:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57: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')]} 19:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57: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', '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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:57: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 functools32==3.2.3-2 19:57:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:06 INFO - 'HOME': '/Users/cltbld', 19:57:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:06 INFO - 'LOGNAME': 'cltbld', 19:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:06 INFO - 'MOZ_NO_REMOTE': '1', 19:57:06 INFO - 'NO_EM_RESTART': '1', 19:57:06 INFO - 'PAGER': '/bin/cat', 19:57:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:06 INFO - 'PWD': '/builds/slave/test', 19:57:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:06 INFO - 'SHELL': '/bin/bash', 19:57:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:06 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:06 INFO - 'USER': 'cltbld', 19:57:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:06 INFO - 'XPC_FLAGS': '0x0', 19:57:06 INFO - 'XPC_SERVICE_NAME': '0', 19:57:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:57:06 INFO - Cleaning up... 19:57:06 INFO - Return code: 0 19:57:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:57: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')]} 19:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57: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')]} 19:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57: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', '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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:57: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 blobuploader==1.2.4 19:57:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:06 INFO - 'HOME': '/Users/cltbld', 19:57:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:06 INFO - 'LOGNAME': 'cltbld', 19:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:06 INFO - 'MOZ_NO_REMOTE': '1', 19:57:06 INFO - 'NO_EM_RESTART': '1', 19:57:06 INFO - 'PAGER': '/bin/cat', 19:57:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:06 INFO - 'PWD': '/builds/slave/test', 19:57:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:06 INFO - 'SHELL': '/bin/bash', 19:57:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:06 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:06 INFO - 'USER': 'cltbld', 19:57:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:06 INFO - 'XPC_FLAGS': '0x0', 19:57:06 INFO - 'XPC_SERVICE_NAME': '0', 19:57:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:06 INFO - Downloading/unpacking blobuploader==1.2.4 19:57: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 19:57: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 19:57: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 19:57: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 19:57:08 INFO - Downloading blobuploader-1.2.4.tar.gz 19:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:57:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:57: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 19:57: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 19:57: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 19:57: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 19:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:57:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:57: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 19:57: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 19:57: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 19:57: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 19:57:09 INFO - Downloading docopt-0.6.1.tar.gz 19:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:57:09 INFO - Installing collected packages: blobuploader, requests, docopt 19:57:09 INFO - Running setup.py install for blobuploader 19:57:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:57:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:57:09 INFO - Running setup.py install for requests 19:57:09 INFO - Running setup.py install for docopt 19:57:10 INFO - Successfully installed blobuploader requests docopt 19:57:10 INFO - Cleaning up... 19:57:10 INFO - Return code: 0 19:57:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:57:10 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')]} 19:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57:10 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')]} 19:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57:10 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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57:10 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 19:57:10 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 19:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:10 INFO - 'HOME': '/Users/cltbld', 19:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:10 INFO - 'LOGNAME': 'cltbld', 19:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:10 INFO - 'MOZ_NO_REMOTE': '1', 19:57:10 INFO - 'NO_EM_RESTART': '1', 19:57:10 INFO - 'PAGER': '/bin/cat', 19:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:10 INFO - 'PWD': '/builds/slave/test', 19:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:10 INFO - 'SHELL': '/bin/bash', 19:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:10 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:10 INFO - 'USER': 'cltbld', 19:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:10 INFO - 'XPC_FLAGS': '0x0', 19:57:10 INFO - 'XPC_SERVICE_NAME': '0', 19:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:57:10 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-7sgmBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:57:10 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-H2lqWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:57:10 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Hmhrk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:57:10 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-duZ3Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:57:10 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-nIlvSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:57:10 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-kPIh6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-CTWeEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-tIweZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-VVXnr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-0hUQ9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-whLTqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-YQONNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-JYqbsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-xN1i8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Vxbk_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:57:11 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-vShINn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:57:12 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-5XXBJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:57:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:57:12 INFO - Running setup.py install for manifestparser 19:57:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:57:12 INFO - Running setup.py install for mozcrash 19:57:12 INFO - Running setup.py install for mozdebug 19:57:12 INFO - Running setup.py install for mozdevice 19:57:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:57:12 INFO - Running setup.py install for mozfile 19:57:12 INFO - Running setup.py install for mozhttpd 19:57:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Running setup.py install for mozinfo 19:57:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Running setup.py install for mozInstall 19:57:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Running setup.py install for mozleak 19:57:13 INFO - Running setup.py install for mozlog 19:57:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Running setup.py install for moznetwork 19:57:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:57:13 INFO - Running setup.py install for mozprocess 19:57:13 INFO - Running setup.py install for mozprofile 19:57:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:57:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:57:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:57:14 INFO - Running setup.py install for mozrunner 19:57:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:57:14 INFO - Running setup.py install for mozscreenshot 19:57:14 INFO - Running setup.py install for moztest 19:57:14 INFO - Running setup.py install for mozversion 19:57:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:57:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:57:14 INFO - Cleaning up... 19:57:14 INFO - Return code: 0 19:57:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:57:14 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')]} 19:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57:14 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')]} 19:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57:14 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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57:14 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 19:57:14 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 19:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:14 INFO - 'HOME': '/Users/cltbld', 19:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:14 INFO - 'LOGNAME': 'cltbld', 19:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:14 INFO - 'MOZ_NO_REMOTE': '1', 19:57:14 INFO - 'NO_EM_RESTART': '1', 19:57:14 INFO - 'PAGER': '/bin/cat', 19:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:14 INFO - 'PWD': '/builds/slave/test', 19:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:14 INFO - 'SHELL': '/bin/bash', 19:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:14 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:14 INFO - 'USER': 'cltbld', 19:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:14 INFO - 'XPC_FLAGS': '0x0', 19:57:14 INFO - 'XPC_SERVICE_NAME': '0', 19:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-LfM0bA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-WbPYUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-1sVORK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-2QnUVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:57:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-wuox8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-j8pjgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-GlCs1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-IPpnSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-66g0jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:57:15 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)) 19:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:57:15 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-WXYW10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-R3xVLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-A9XAms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-A6bt4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-9qp_93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Uh0V9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-J8P8iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:57:16 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)) 19:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:57:16 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-BrDlbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:57:16 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)) 19:57:16 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)) 19:57:16 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)) 19:57:16 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)) 19:57:16 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:57:16 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:57:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:57:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:57:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:57:18 INFO - Downloading blessings-1.6.tar.gz 19:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:57:18 INFO - Installing collected packages: blessings 19:57:18 INFO - Running setup.py install for blessings 19:57:18 INFO - Successfully installed blessings 19:57:18 INFO - Cleaning up... 19:57:18 INFO - Return code: 0 19:57:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:57:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:57:18 INFO - Reading from file tmpfile_stdout 19:57:18 INFO - Current package versions: 19:57:18 INFO - blessings == 1.6 19:57:18 INFO - blobuploader == 1.2.4 19:57:18 INFO - docopt == 0.6.1 19:57:18 INFO - functools32 == 3.2.3-2 19:57:18 INFO - jsonschema == 2.5.1 19:57:18 INFO - manifestparser == 1.1 19:57:18 INFO - mozInstall == 1.12 19:57:18 INFO - mozcrash == 1.0 19:57:18 INFO - mozdebug == 0.1 19:57:18 INFO - mozdevice == 0.51 19:57:18 INFO - mozfile == 1.2 19:57:18 INFO - mozhttpd == 0.7 19:57:18 INFO - mozinfo == 0.9 19:57:18 INFO - mozleak == 0.1 19:57:18 INFO - mozlog == 3.4 19:57:18 INFO - moznetwork == 0.27 19:57:18 INFO - mozprocess == 0.25 19:57:18 INFO - mozprofile == 0.28 19:57:18 INFO - mozrunner == 6.13 19:57:18 INFO - mozscreenshot == 0.1 19:57:18 INFO - mozsystemmonitor == 0.3 19:57:18 INFO - moztest == 0.8 19:57:18 INFO - mozversion == 1.4 19:57:18 INFO - psutil == 3.1.1 19:57:18 INFO - requests == 1.2.3 19:57:18 INFO - wsgiref == 0.1.2 19:57:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:57:18 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')]} 19:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57:18 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')]} 19:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57:18 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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57:18 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 19:57:18 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 19:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:18 INFO - 'HOME': '/Users/cltbld', 19:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:18 INFO - 'LOGNAME': 'cltbld', 19:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:18 INFO - 'MOZ_NO_REMOTE': '1', 19:57:18 INFO - 'NO_EM_RESTART': '1', 19:57:18 INFO - 'PAGER': '/bin/cat', 19:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:18 INFO - 'PWD': '/builds/slave/test', 19:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:18 INFO - 'SHELL': '/bin/bash', 19:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:18 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:18 INFO - 'USER': 'cltbld', 19:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:18 INFO - 'XPC_FLAGS': '0x0', 19:57:18 INFO - 'XPC_SERVICE_NAME': '0', 19:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 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)) 19:57:19 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:57: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 19:57: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 19:57: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 19:57: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 19:57:20 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:57:20 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)) 19:57:20 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)) 19:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:57:20 INFO - Installing collected packages: marionette-driver 19:57:20 INFO - Running setup.py install for marionette-driver 19:57:20 INFO - Successfully installed marionette-driver 19:57:20 INFO - Cleaning up... 19:57:20 INFO - Return code: 0 19:57:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:57:20 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')]} 19:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:57:20 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')]} 19:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:57:20 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 0x10073bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10073f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e1c72040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, '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.FklG4k30dl/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.tKoahAvQ5Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 19:57:20 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 19:57:20 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 19:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:20 INFO - 'HOME': '/Users/cltbld', 19:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:20 INFO - 'LOGNAME': 'cltbld', 19:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:20 INFO - 'MOZ_NO_REMOTE': '1', 19:57:20 INFO - 'NO_EM_RESTART': '1', 19:57:20 INFO - 'PAGER': '/bin/cat', 19:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:20 INFO - 'PWD': '/builds/slave/test', 19:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:20 INFO - 'SHELL': '/bin/bash', 19:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:20 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:20 INFO - 'USER': 'cltbld', 19:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:20 INFO - 'XPC_FLAGS': '0x0', 19:57:20 INFO - 'XPC_SERVICE_NAME': '0', 19:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:57:21 INFO - Cleaning up... 19:57:21 INFO - Return code: 0 19:57:21 INFO - Running post-action listener: _resource_record_post_action 19:57:21 INFO - Running post-action listener: _start_resource_monitoring 19:57:21 INFO - Starting resource monitoring. 19:57:21 INFO - [mozharness: 2017-05-26 02:57:21.207635Z] Finished create-virtualenv step (success) 19:57:21 INFO - [mozharness: 2017-05-26 02:57:21.208651Z] Running install step. 19:57:21 INFO - Running pre-action listener: _resource_record_pre_action 19:57:21 INFO - Running main action method: install 19:57:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:57:21 INFO - Reading from file tmpfile_stdout 19:57:21 INFO - Detecting whether we're running mozinstall >=1.0... 19:57:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:57:21 INFO - Reading from file tmpfile_stdout 19:57:21 INFO - Output received: 19:57:21 INFO - Usage: mozinstall [options] installer 19:57:21 INFO - Options: 19:57:21 INFO - -h, --help show this help message and exit 19:57:21 INFO - -d DEST, --destination=DEST 19:57:21 INFO - Directory to install application into. [default: 19:57:21 INFO - "/builds/slave/test"] 19:57:21 INFO - --app=APP Application being installed. [default: firefox] 19:57:21 INFO - mkdir: /builds/slave/test/build/application 19:57:21 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'] 19:57:21 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 19:57:42 INFO - Reading from file tmpfile_stdout 19:57:42 INFO - Output received: 19:57:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:57:42 INFO - Running post-action listener: _resource_record_post_action 19:57:42 INFO - [mozharness: 2017-05-26 02:57:42.711338Z] Finished install step (success) 19:57:42 INFO - [mozharness: 2017-05-26 02:57:42.711490Z] Running run-tests step. 19:57:42 INFO - Running pre-action listener: _resource_record_pre_action 19:57:42 INFO - Running main action method: run_tests 19:57:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:57:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:57:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:57:42 INFO - 2017-05-25 19:57:42.724 screenresolution[1628:8100] starting screenresolution argv=screenresolution get 19:57:42 INFO - 2017-05-25 19:57:42.727 screenresolution[1628:8100] Display 0: 1600x1200x32@60 19:57:42 INFO - 2017-05-25 19:57:42.732 screenresolution[1629:8103] starting screenresolution argv=screenresolution list 19:57:42 INFO - Available Modes on Display 0 19:57:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:57:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:57:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:57:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:57:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:57:42 INFO - Intel Iris: 19:57:42 INFO - Chipset Model: Intel Iris 19:57:42 INFO - Type: GPU 19:57:42 INFO - Bus: Built-In 19:57:42 INFO - VRAM (Dynamic, Max): 1536 MB 19:57:42 INFO - Vendor: Intel (0x8086) 19:57:42 INFO - Device ID: 0x0a2e 19:57:42 INFO - Revision ID: 0x0009 19:57:42 INFO - Displays: 19:57:42 INFO - Display: 19:57:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:57:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:57:42 INFO - Display Serial Number: 1600x1200 60 19:57:42 INFO - Main Display: Yes 19:57:42 INFO - Mirror: Off 19:57:42 INFO - Online: Yes 19:57:42 INFO - Rotation: Supported 19:57:42 INFO - Return code: 0 19:57:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:57:42 INFO - Python 2.7.3 19:57:42 INFO - Return code: 0 19:57:42 INFO - grabbing minidump binary from tooltool 19:57: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')]} 19:57: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 0x10044bd30>, '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 0x1003ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100440be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:57: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 19:57: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 19:57: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 19:57:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:57:43 INFO - Return code: 0 19:57:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:57:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:57:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:57:43 INFO - ENV: RUST_BACKTRACE is now 1 19:57:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:57:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:57: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/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 19:57: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/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0247 --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 19:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FklG4k30dl/Render', 19:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:43 INFO - 'HOME': '/Users/cltbld', 19:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:43 INFO - 'LOGNAME': 'cltbld', 19:57:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:57:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:43 INFO - 'MOZ_NO_REMOTE': '1', 19:57:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:57:43 INFO - 'NO_EM_RESTART': '1', 19:57:43 INFO - 'PAGER': '/bin/cat', 19:57:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:43 INFO - 'PWD': '/builds/slave/test', 19:57:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:43 INFO - 'RUST_BACKTRACE': '1', 19:57:43 INFO - 'SHELL': '/bin/bash', 19:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tKoahAvQ5Z/Listeners', 19:57:43 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 19:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:43 INFO - 'USER': 'cltbld', 19:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:43 INFO - 'XPC_FLAGS': '0x0', 19:57:43 INFO - 'XPC_SERVICE_NAME': '0', 19:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57: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/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 19:57:43 INFO - mozversion application_buildid: 20170525183437 19:57:43 INFO - mozversion application_changeset: c2ff59dd31bce41bc9108939e86618017943b88d 19:57:43 INFO - mozversion application_display_name: Nightly 19:57:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:57:43 INFO - mozversion application_name: Firefox 19:57:43 INFO - mozversion application_remotingname: firefox 19:57:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:57:43 INFO - mozversion application_vendor: Mozilla 19:57:43 INFO - mozversion application_version: 55.0a1 19:57:43 INFO - mozversion platform_buildid: 20170525183437 19:57:43 INFO - mozversion platform_changeset: c2ff59dd31bce41bc9108939e86618017943b88d 19:57:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:57:43 INFO - mozversion platform_version: 55.0a1 19:57:43 INFO - using testdate: 1495767463 19:57:43 INFO - actual date: 1495767463 19:57:43 INFO - starting webserver on 'localhost:49228' 19:57:43 INFO - SUITE-START | Running 2 tests 19:57:43 INFO - TEST-START | dromaeo_css 19:57:43 INFO - operating with platform_type : mac_ 19:57:43 INFO - Initialising browser for dromaeo_css test... 19:57:43 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpf_32RT/profile 19:57:43 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 19:58:16 INFO - TEST-INFO | 1639: killed by SIGTERM 19:58:16 INFO - Browser initialized. 19:58:16 INFO - Running cycle 1/1 for dromaeo_css test... 19:58:16 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpf_32RT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:58:18 INFO - PID 1647 | 2017-05-25 19:58:18.967 plugin-container[1648:8604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 19:58:18 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:58:18 INFO - PID 1647 | 2017-05-25 19:58:18.969 plugin-container[1648:8604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 19:58:18 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:58:18 INFO - PID 1647 | 2017-05-25 19:58:18.969 plugin-container[1648:8604] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:58:19 INFO - PID 1647 | 2017-05-25 19:58:19.501 plugin-container[1649:8650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 19:58:19 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:58:19 INFO - PID 1647 | 2017-05-25 19:58:19.502 plugin-container[1649:8650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 19:58:19 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:58:19 INFO - PID 1647 | 2017-05-25 19:58:19.502 plugin-container[1649:8650] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:58:28 INFO - PID 1647 | RSS: Main: 242786304 19:58:28 INFO - PID 1647 | 20:01:31 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 20:01:32 INFO - PID 1647 | RSS: Main: 255500288 20:01:32 INFO - PID 1647 | 20:04:34 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 20:04:35 INFO - PID 1647 | RSS: Main: 269758464 20:04:35 INFO - PID 1647 | 20:07:36 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 20:07:37 INFO - PID 1647 | RSS: Main: 268746752 20:07:37 INFO - PID 1647 | 20:10:39 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 20:10:39 INFO - PID 1647 | RSS: Main: 272412672 20:10:39 INFO - PID 1647 | 20:13:42 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 20:13:43 INFO - PID 1647 | RSS: Main: 272744448 20:13:43 INFO - PID 1647 | 20:16:44 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 20:16:45 INFO - PID 1647 | RSS: Main: 275341312 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | __start_tp_report 20:16:45 INFO - PID 1647 | _x_x_mozilla_page_load 20:16:45 INFO - PID 1647 | _x_x_mozilla_page_load_details 20:16:45 INFO - PID 1647 | |i|pagename|runs| 20:16:45 INFO - PID 1647 | |0;dojo.html;2986.3877905029467;3610.2057547339577;3632.4914511968313;3650.908727281819;3651.580068292147;6119.807225076668;6143.846403839906;6199.938000619993;6213.53398495113;6246.187995560582;6200;6204.069389591552;6218.782342618009;6219.689015549227;6254.874902501959;5439.5920305977015;5528.308961379827;5528.474794894479;5548.6948217848;5555.083411237141;5579.2746942897365;5637.182608521762;5645.01212287848;5656.349519805213;5668.319801623811;5429;5449.155380915964;5536.058869992111;5578.30271216098;5618.775248990036;5503.477169668875;5519.751611177507;5522.337319521643;5546.639468434539;5569.192467092289;5499.202615620713;5503.2020357048405;5509.421510741395;5517.117261238182;5563.36021357541;6178.165947597062;6254.624722516618;6280.434760871543;6291.213598300213;6296.433321001086;6574.1124948131865;6586.7035983380865;6745.258021617618;6767.830804229934;6773;5668;6819.795406137774;6844.349786770249;6855.5543889647015;6869.896951545681;5471.644343117724;6200.193974783249;6322.620642761449;6326.43062124411;6360.7137678804565;6766.357196066366;6859.5541289816;6940.514164008572;6977.092977912839;6981.82545436358;6271.686415679289;6432.839179020469;6587.967060164764;6616.305287944697;6653.234877989094;6562;6689.46484281266;6785.830354241183;6792;6818.693158807866;6690.933090669128;6707.429868461138;6716.093327400836;6717.832054198684;6734.730610775612;6354.142190804275;6437.549371544041;6582.275949645536;6584.934150658432;6661.100751398596;5380.031594312983;5383.892322153613;5398.973005134949;5400.1899715043055;5412.729363531807;4743.83396581118;4746.976265118652;4760.047990401933;4763.3807605011125;4774.474807771142;6093.451589356979;6257.968710156511;6272.278687950853;6316.115743795872;6375.426211640882;6815.965920170367;6828.556143850634;6833.487488438387;6837.282085381063;6851.48613853963;5767.731099158178;6336.239651241788;6365.745370185141;6388.680565971683;6401.231852177769;4696.694714843524;5586.91619625706;5848.4151584841175;5855.326637436664;5941.287045554561;6928.44572434204;6939.791806245821;6957.1651401831095;6979.057827193264;6992.895106573507;6784.6946887389195;6792.524523283323;6792.864142717217;6807.21717002551;6807.727690892309;6641.136652235372;6899.275576064641;6929.099217101744;6967.442604591722;7010.579365238102;6397.520185985976;6602.735890564323;6645.169353830771;6656.966715166393;6715.127033485615;6791.490638202055;6810.455163587;6837.794866154023;6845.760398386033;6863.759768408082;6340.1123842661145;6406.90389644165;6446.613203207822;6513.413792758674;6628;6522.412982831568;6571.244306904767;6574.178227721535;6581.605103693793;6602.966985165043;5425.457454254543;5859.44335288147;5869.647821130746;6453.290138084901;6459.063435801689;5114.74426278692;6297.2128483939505;6542.051402546509;6544.44372228366;6581.07864898905;6305.716242768995;6345.302016778243;6375.394337537927;6386.680665966776;6396.936030639634 20:16:45 INFO - PID 1647 | |1;ext.html;723.8479919216965;768.4774353439659;813.069035953833;1229.2562999385366;1285.3059347952105;39746.40380394289;40080.79959600201;40489.76081913447;40706.77879663608;40936.590634093685;40025;40358.39462408066;40675.79662101685;40684.79657601708;41200.79399603005;7903.0516338039515;8060.476069055522;8142.43002989791;8259.256666899977;8296.792580185513;6811.557248778839;8330.125336839605;8351.749447516553;8387.454815437008;8492.872606910902;8261.793455163608;8286.58567071647;8383.329333653293;8407.159284071575;8514.957425212866;8234.300084492825;8283.29591984682;8292.29515491181;8371.246587807127;8432.620532076071;8046;8073.354131669453;8104.756857294232;8247.670093196264;8280.50316980977;35247.471287930704;35848;35934.10164745876;36041.45937810935;36179.4573081404;3438.6561343865665;3481.721462283011;3494.30113977203;3574.3566158091535;3589.766665166782;29575.408491830258;30346.54480182799;30836.84581577078;30903.690963090306;30909.381812363852;3028.818270903753;3050.877964881402;3108.4094022135946;3121.7502599792188;3153.6530981592005;231890;239321.60678393167;272169.63915180496;289771;302063.97936020576;47270.76364618224;47516.52483475121;48266;48314.75842620764;48426.75786621044;48217;49773.75113124411;49921.505494615754;50253.497465024884;50503.49496505062;13991.86008139926;14128.434862605587;14140.575782726535;14270.3578338975;14399.97600503906;11323.773524529463;11411.885881141083;11426.885731142582;11707.180497365103;11754.70613234676;10672.62645807393;10694.572217111228;13240.271785051915;13567.796483052762;13585.388657510435;6014.699265036731;6034.939650603438;6039.698015099315;6154.692265386713;6230.320961986299;971.4365667912593;1005.2862467647906;1007.4207330784828;1053.3942982784777;1074.398336931321;23814.761852381256;24548.509029819303;24632.507349853262;24682.629760553606;24696.25911222666;1095.3811096730321;1125.4654039331251;1131.8924702153447;1155.8035134027236;1157.5311998640395;11856.22934509254;12335.568255111208;12378.257304561756;12581.874181258254;12676.80984785229;308816;310479;317307;318291;319623;43925.78037109794;44076.77961610172;53867.19199212015;54305.91388172215;55112;1483.8961272711022;1559.3606621285446;1568.3021055630363;1574.842515748393;1592.1163754116576;18792.812071879653;23558.93984770655;23636.409089772893;23724.644130338405;23985;36985.445218321205;37855.62144378631;38073.85778426652;38155.046123847125;38273;256913;264495;264612;264903;265761;33603.49594756032;34157.48763768496;34214;34566.30867382588;34823.4776478348;42931.70682931682;46195;46484.30273545832;46699;46821.82945426391;29403.85298073496;29942.401151976403;30897.22756931005;32351.514727278638;32743.345133097682;25323.48059116459;28507.572386413805;31294;31413.05760827179;31939;36488;36891.07772305606;36932.26135477329;38127;38684.6131538681;30351;31478.370432591677;31579.578918948242;32059.519107212946;32318.779563634376;20902.790972090086;21277.89361053247;24056.27831165068;24556;24814.751852481244 20:16:45 INFO - PID 1647 | |2;jquery.html;420.83377066058927;427.1307888447011;439.54507085166847;445.897443587975;453.75950746104564;17437;18751.718724219132;19290.9389983551;20664.380068597966;21716.457088572795;18677.719834202464;22528.66207006892;22608.773912260873;22639.773602263973;22736.886315568398;5429.429909859457;5604.159376093598;5802.82591522255;5854.5023672987845;5941.554383421239;5754.16564598133;5774.729559496904;5861.120831875211;5896.027155519356;5936.436038576328;5780.1907732917425;5790.68151251681;5809;5885.264341957255;5951.345352011275;4623.42207224097;5769.5672824538115;5818.5345172386105;5929.14027466015;5934.376890426508;5196.168613021899;5197.610179236534;5256.842294731164;5362.973185134088;5395.7032363220005;26974.325641858915;27175.592366114524;27507.174784756287;27664.723352766418;27770.028049018187;2117.047328702075;2150.956980860374;2158.438805910459;2160.459885028743;2170.0343347210496;13241.73516529664;17512.474625761246;17740.46778596644;18034.549136271566;18328.725069123975;2090.446031801574;2112.4402033461147;2123.766385697572;2143.389134096775;2178.6949827024227;111724;152138.23930880387;155183.44816551803;158799.20600396924;163615;12098.274103553815;12366.258024518558;12770.169938953939;12786.29675367845;12797.36013199355;15803.20983950798;15873.04761714301;15929.442469513511;16136.757948630546;16204;8828.823423531494;8882.86675699865;8890.73327800154;8925.419847709878;8974.551272436353;8411;8574.914250857411;8652.826943461096;8656.95671521651;8842;7761.495502792413;7850.175731548105;7859.292663660183;8051.557164356017;8074.273315401642;2103.463616777712;2128.4572434029533;2130.616489031989;2203.504211552388;2329.7670232976566;847.2544161138283;920.8940971788196;921.4563407589554;942.4439580647314;958.3195930889146;18795.436136915916;19719.40841774749;20364.389068327975;20859.58280834375;20876.895615521826;803.658445160816;885.2386947225381;955.3694561589317;979.2313034268066;1008.6469735592631;10525.9473702631;10575.36547807134;10651;10709.410982395888;10743.89256107429;415553;451921;492911;498505;505524;88636;95053.14840554794;123652;124085;129003;1047.8218702820539;1177.2877409167331;1199.9160058795976;1227.7851376009162;1236.4436003798146;17659.940403575827;21229.893850530647;21454.463638409165;21789;22198;35762;54604;57843.71078144582;58523;58528.12207816971;56274.43725562692;70655;77694;78321.4335713294;78430;7181.748638797617;8829.646814127364;9013.864792027995;9057.954710226406;9141.63433462654;13914.234717090658;14079.366428510537;14363.353649086026;14469;14751.778723318945;12001.63995080149;12327.69180770488;12333.753324933272;12357.93821030889;12473.376331183223;10531.631392901212;10778.13774898022;10861.294015888941;11433.006462783185;11538.307701537598;12033.518659253532;12245;12447.439865205906;12611.927986121282;12677.239365638092;8518.78703032429;8710;8752.64989400417;8906.198442140238;8970.237529809778;11828.17202795796;11859.932606065495;11954;12151.4203153593;12332.568360107352 20:16:45 INFO - PID 1647 | |3;mootools.html;1402.9368678409471;1420.7584710599197;1425.921574313413;1446.6817300193954;1491.9925400372997;11209.551617935273;11303.434828258596;11892.82160767587;11994.64016079518;12167.209131406464;11694.356810375426;11729.006068968274;11732.120090993167;11735.178537502377;11930.343831089287;2542.377117606189;2550.4006558458746;2560.2703229579574;2561.4108754986364;2565.384307766132;2437.9512409751796;2445.4375493636385;2459.077845807822;2464.383904023994;2523.7980961523126;2503.0613519930025;2503.173952595648;2504.0860086068546;2515.610080437535;2538.7715105640486;2385.964210536835;2417.2385698504913;2420.9394765130837;2433.221369161869;2459.9631005534843;2421.2373102472666;2515.157422263544;2526.7978561715204;2534.974650253492;2543.3514453814346;8144.959275203645;11205.551777928873;11403.3728144952;11513.942430287878;11834.05327573792;6316.652584107872;6555.901661475082;6562.1469209003;6628.569143005738;6654.567453115532;4184.100418410026;4186.665066794649;4267.359896015591;4281.143771245764;4305.50486694031;941.4586612697726;1189.6966273600262;1239.900807935372;1241.2428418664608;1247.7442124364484;15074.472393466176;15557.45548905783;16321.673566528721;16440.013599184087;16662.83337166625;5252.369715634149;5321.787128514893;5542.501174894279;5570.716006437422;5670.234518340054;11513.424328783527;11700.70748231301;11707.824382634268;11932.821007684892;12049.216800907912;6214.4407003369915;6280.780172693934;6298.307186209513;6397;6484.157059582224;5823.621464604787;5860.619059761188;5935.6735379553675;5969.522438204933;6063.363346848518;4359.716618419792;4366.214081465367;4400.669949753781;4424.203643344164;4461.665375096816;1251.4055823483955;1261.861195268538;1266.468083404972;1277.4032459425719;1297.7599144158362;751.6993202718957;763.3587786259569;779.4660657449665;801.6352559585491;812.8252425728497;6636.668166591748;6937.54905931123;6948.305169483011;6955.374016338554;7007.579545227302;919.1891972813546;947.8009617980164;967.4775621164492;980.8479685648736;1000.2248257600418;3835.5589107252463;3850.133719912997;3876.341022026262;3928.6660633846445;4064.4309796628495;14710;14834.851651483563;14900.925495372454;14939.028963117362;15131.621709457351;11691.88308116925;14529.273536323353;14787.92606036963;14892.0320179188;15036.398544058115;7916.881246781303;8459.915400845914;8477;8524.829503410021;8546.658133674708;5959.1657167995645;6178;6657.2677005528385;6960.164780226405;6999.160100787938;9936.652217172365;12768.936155319536;12769.872301276868;13093.410796513988;13182.077254592086;13227.272500012592;13785.310734463437;13821.861781382058;13981.580552583438;14158.716825663223;7809.648565814438;8846.690365837165;9057.909420905706;9096.226820720312;9264.629414823332;10471.3193642413;10481.42352170638;10690.305130166515;10690.83963740529;10794.028537431668;8940.374173808032;8993.235574976201;9053.230475409664;9150;9217.953910230406;7311.634418278958;8720.607572659372;8841.823163536821;8940.955295223483;9051.230645395215;8002;8069.959650201711;8166.714165004197;8277.95861020691;8357.247847693738;5945.732442040032;5951.226340575698;6024.66864322449;6097.115918191926;6116.17431646722;5956.463918247206;6087.786927457518;6107.8778424430375;6108.297545782292;6146.354632763496 20:16:45 INFO - PID 1647 | |4;prototype.html;401.66862338570684;402.7764590652188;404.1351507773367;408.54447291270236;409.83606557377067;4860.684055536388;4927.778249978751;5948;5982.248165843712;6079.4528492435675;5437.075697131488;5658.745356458959;5878.294604647448;5918.721292302473;5962.25471816023;4021.557628660852;4457.799399027052;4549.408576885;4554.43069616298;4600.930986035212;3895.2793733159315;4053.756774593534;4462.843800466984;4488;4499.572540608653;3429.7599168058246;3982.5220973483224;3984.9003774905714;4274.871753847374;4334.804933777972;3573.3389322975204;3796.563395209531;3811.866584669537;4130.669546436278;4269.509006464265;3012.638483381986;3139.587185766414;3227.3706627207703;3696.31618150643;3708.609271523179;4681.665725268316;5414.214938833887;5682.658301196861;5781.682007489587;5797.681127537984;4269.914601707979;4421.6904816662845;4605.815767369301;4994.800207991676;5059.595232381401;5938.792142275043;6248.593841400295;6265.686715664198;6392.169018027673;6413.16628838248;3521.29574085184;4046.3728122141115;4125.195586860564;4217.34631132175;4296.312589985588;5106.744662766847;6020.819375418744;6184.96907515464;6650.600963942131;6785.389314961628;4241.533431322552;4249.80875860587;4283.336082907154;4390.934135987963;4394.033312671208;4071.6539094176733;4336.674749393808;4358.060663004996;4404.8458303959205;4449.532799056117;4757.048590281957;4830.830920917751;5103.897922041612;5205.895882082413;5235.947640523622;5428.6199966002005;6112.51099912006;6299.858048263613;6339.492840572743;6495.545311828221;1465.992670036643;1474.7640377539542;1484.5546336099128;1501.3919362658141;1522.7715842623695;932.6036434515304;984.5527075199445;987.6790043235977;991.5637119667323;1026.8408396698524;5300.501668898509;5847.239858818326;6063.453349593182;6172.444479996732;6216.720247588869;6215.782447614311;6550.853600619873;6581.782370261517;6701.966490167518;6781.220159681699;5427.9185812212845;5644.701718604661;5794.855128621818;6054.334023257438;6203.16257305267;2827.3355761396056;3227.9186472531965;3394.7114495267683;3455.7235421166747;3506.474028895635;5556.777728890799;6383.616982981036;6766.526343156031;6770.729170833111;6808.183018037867;5947.940520594825;6762.188537375448;6768.864622707519;6772.966135169293;6784.660766961632;5456.61803673739;5534.916976245359;6117;6405.839854003594;6925.722971081201;5182.689038657692;6367.204099487564;6556.573822701509;6690.832729181615;6718.428933540704;6346.90479642815;6382.968085159545;6433.906235939995;6452.709458108303;6540.738370465128;6684.532082754161;6753.493487988322;6760.459163266829;6911.274316196727;6939.236683964659;4228.386883901756;4259.701820872509;4277.294246449301;4322.416473776126;4403.8458653946955;6394.5523813334485;6516.57642253252;6574.572652777554;6621.410861393328;6746.245976046362;6366.458850997532;6383.489320854228;6412.070249813844;6421.133147025092;6545.345465453416;5424.83725488236;6178.783742568989;6412.711427985845;6490.156279683611;6526.771562995463;4577.519360467103;5454.400015998316;5884.617499862496;6145.754169833335;6359.90460143089;5185.896282074413;6293.244810622755;6356.491480681627;6398.264199617103;6498.447631951337;5558.110702287615;6198.72105755233;6208.937910620836;6232.039781103939;6376.234851817811 20:16:45 INFO - PID 1647 | |5;yui.html;758.7496126278327;819.9057108432525;837.2464781696476;840.4159109418954;882.5349919134235;14385.280735963212;14534.418623255084;16251.75622365664;16726.41457548986;16786.91606541967;14112.506062287823;15931.20343982802;17259.568510787238;17342.046187459684;17380.82619173808;938.9014153513886;964.4599024546277;981.1807141037242;1009.2178561614734;1017.9389236645825;984.916282116021;988.85661579071;1035.6892932120334;1045.29442626227;1051.8527406163143;1035.6012935020033;1049.1816383221062;1079.9460026998618;1085.2240647936724;1091.2797553614616;1075.9031687148195;1076.34343050739;1077.7467295185625;1081.9891801081967;1081.9891801082006;778.1790211327047;789.5894135049742;792.4967645545063;796.2117503671324;804.252045597594;16679.91660041704;16978.405755798613;17008.319667213298;17188.226529806063;17261.482155535356;17075.060871652055;17350.56623584408;17495.737563936553;17561.912190438965;18131.54671133219;13990.510332138429;14474.203918784462;14583.197924114169;14848.703025939529;15036.849631503765;3523.7885726856216;3526.312369088029;3542.468629705539;3548.7515873888838;3570.357335679577;128033;134404;134407.32796336053;135797.96303055462;153305.46694533134;13537.864621353661;13897.513587024405;13973.161610303414;13985.790213146607;14431.35058922351;26108.477830443284;26711.866440667673;26935.461290774074;26971.595426068623;27002.5949610756;7625.961870190614;7790.727324543614;7918.049834019955;7930.841383172305;7934.96845410093;3542.6006727342556;3621.9637803622154;3940.113474468279;3969.801509924492;3989.6209860063245;3864.14988702485;4035.3139966205817;4713.882152946135;4803.519648035239;4873.951260487421;787.6652422720435;915.2586405011962;943.7546237978175;945.3713280668416;947.3179310896281;505.5677395826589;635.758411803521;646.3181343682367;668.9998452313822;676.9541059063758;10067.798644027078;10878.510467029002;11214.607488737855;12119.636410907688;12570.308633025088;542.8751387180891;628.9716962736687;643.3919945651314;644.4168027934727;656.9277379488252;5442.91835622466;5518.44815518453;5562.805301814417;5678.176664383641;5684.971575142098;139112;140719;140939.2953035228;145376.27311863372;149203;108816;112605.01683266085;113405;113938.72122557502;114671.42664286793;83630;100448.4977575122;108087.13607887663;108653;110380;10964.177686673373;11064.668059958214;11085.224034317844;11251.381174035507;11347.773044539228;40471.79764101278;40844.59155408408;45021.32468013048;45183;45191;48060;48674;53244;60992.08511872237;63038;12768;12914.935425323189;12985.480580776662;13224.669383265495;13393.799093013808;15638.218089095728;15830.28763705613;16059.919700401424;16086.276117574504;16352.345906164097;14834.480793172188;14893.851061489246;14920.62698432548;14963.625909352353;15327.46353877609;14711.779323310373;16278.186090695655;16348.918255408647;17473.038982855556;17931.282748689908;17069.975801451954;17133.65732685364;17185.484435466456;17282.308707652057;17507;11562.537498499965;11890.286582804712;12375.75248495043;12402.007839372649;12489.812652810397;13161;13464.922806175678;13870.722585548434;13927.164370137823;14192.92903535517 20:16:45 INFO - PID 1647 | __end_tp_report 20:16:45 INFO - PID 1647 | __start_cc_report 20:16:45 INFO - PID 1647 | _x_x_mozilla_cycle_collect,405 20:16:45 INFO - PID 1647 | __end_cc_report 20:16:45 INFO - PID 1647 | __startTimestamp1495768605135__endTimestamp 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | ------- Summary: start ------- 20:16:45 INFO - PID 1647 | Number of tests: 6 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6167.20 Median:6365.75 stddev:741.75 (11.7%) stddev-sans-first:700.80 20:16:45 INFO - PID 1647 | Values: 2986.4 3610.2 3632.5 3650.9 3651.6 6119.8 6143.8 6199.9 6213.5 6246.2 6200.0 6204.1 6218.8 6219.7 6254.9 5439.6 5528.3 5528.5 5548.7 5555.1 5579.3 5637.2 5645.0 5656.3 5668.3 5429.0 5449.2 5536.1 5578.3 5618.8 5503.5 5519.8 5522.3 5546.6 5569.2 5499.2 5503.2 5509.4 5517.1 5563.4 6178.2 6254.6 6280.4 6291.2 6296.4 6574.1 6586.7 6745.3 6767.8 6773.0 5668.0 6819.8 6844.3 6855.6 6869.9 5471.6 6200.2 6322.6 6326.4 6360.7 6766.4 6859.6 6940.5 6977.1 6981.8 6271.7 6432.8 6588.0 6616.3 6653.2 6562.0 6689.5 6785.8 6792.0 6818.7 6690.9 6707.4 6716.1 6717.8 6734.7 6354.1 6437.5 6582.3 6584.9 6661.1 5380.0 5383.9 5399.0 5400.2 5412.7 4743.8 4747.0 4760.0 4763.4 4774.5 6093.5 6258.0 6272.3 6316.1 6375.4 6816.0 6828.6 6833.5 6837.3 6851.5 5767.7 6336.2 6365.7 6388.7 6401.2 4696.7 5586.9 5848.4 5855.3 5941.3 6928.4 6939.8 6957.2 6979.1 6992.9 6784.7 6792.5 6792.9 6807.2 6807.7 6641.1 6899.3 6929.1 6967.4 7010.6 6397.5 6602.7 6645.2 6657.0 6715.1 6791.5 6810.5 6837.8 6845.8 6863.8 6340.1 6406.9 6446.6 6513.4 6628.0 6522.4 6571.2 6574.2 6581.6 6603.0 5425.5 5859.4 5869.6 6453.3 6459.1 5114.7 6297.2 6542.1 6544.4 6581.1 6305.7 6345.3 6375.4 6386.7 6396.9 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:43541.30 Median:33173.42 stddev:74151.48 (223.5%) stddev-sans-first:74289.80 20:16:45 INFO - PID 1647 | Values: 723.8 768.5 813.1 1229.3 1285.3 39746.4 40080.8 40489.8 40706.8 40936.6 40025.0 40358.4 40675.8 40684.8 41200.8 7903.1 8060.5 8142.4 8259.3 8296.8 6811.6 8330.1 8351.7 8387.5 8492.9 8261.8 8286.6 8383.3 8407.2 8515.0 8234.3 8283.3 8292.3 8371.2 8432.6 8046.0 8073.4 8104.8 8247.7 8280.5 35247.5 35848.0 35934.1 36041.5 36179.5 3438.7 3481.7 3494.3 3574.4 3589.8 29575.4 30346.5 30836.8 30903.7 30909.4 3028.8 3050.9 3108.4 3121.8 3153.7 231890.0 239321.6 272169.6 289771.0 302064.0 47270.8 47516.5 48266.0 48314.8 48426.8 48217.0 49773.8 49921.5 50253.5 50503.5 13991.9 14128.4 14140.6 14270.4 14400.0 11323.8 11411.9 11426.9 11707.2 11754.7 10672.6 10694.6 13240.3 13567.8 13585.4 6014.7 6034.9 6039.7 6154.7 6230.3 971.4 1005.3 1007.4 1053.4 1074.4 23814.8 24548.5 24632.5 24682.6 24696.3 1095.4 1125.5 1131.9 1155.8 1157.5 11856.2 12335.6 12378.3 12581.9 12676.8 308816.0 310479.0 317307.0 318291.0 319623.0 43925.8 44076.8 53867.2 54305.9 55112.0 1483.9 1559.4 1568.3 1574.8 1592.1 18792.8 23558.9 23636.4 23724.6 23985.0 36985.4 37855.6 38073.9 38155.0 38273.0 256913.0 264495.0 264612.0 264903.0 265761.0 33603.5 34157.5 34214.0 34566.3 34823.5 42931.7 46195.0 46484.3 46699.0 46821.8 29403.9 29942.4 30897.2 32351.5 32743.3 25323.5 28507.6 31294.0 31413.1 31939.0 36488.0 36891.1 36932.3 38127.0 38684.6 30351.0 31478.4 31579.6 32059.5 32318.8 20902.8 21277.9 24056.3 24556.0 24814.8 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:32437.17 Median:22624.27 stddev:80999.83 (358.0%) stddev-sans-first:81191.39 20:16:45 INFO - PID 1647 | Values: 420.8 427.1 439.5 445.9 453.8 17437.0 18751.7 19290.9 20664.4 21716.5 18677.7 22528.7 22608.8 22639.8 22736.9 5429.4 5604.2 5802.8 5854.5 5941.6 5754.2 5774.7 5861.1 5896.0 5936.4 5780.2 5790.7 5809.0 5885.3 5951.3 4623.4 5769.6 5818.5 5929.1 5934.4 5196.2 5197.6 5256.8 5363.0 5395.7 26974.3 27175.6 27507.2 27664.7 27770.0 2117.0 2151.0 2158.4 2160.5 2170.0 13241.7 17512.5 17740.5 18034.5 18328.7 2090.4 2112.4 2123.8 2143.4 2178.7 111724.0 152138.2 155183.4 158799.2 163615.0 12098.3 12366.3 12770.2 12786.3 12797.4 15803.2 15873.0 15929.4 16136.8 16204.0 8828.8 8882.9 8890.7 8925.4 8974.6 8411.0 8574.9 8652.8 8657.0 8842.0 7761.5 7850.2 7859.3 8051.6 8074.3 2103.5 2128.5 2130.6 2203.5 2329.8 847.3 920.9 921.5 942.4 958.3 18795.4 19719.4 20364.4 20859.6 20876.9 803.7 885.2 955.4 979.2 1008.6 10525.9 10575.4 10651.0 10709.4 10743.9 415553.0 451921.0 492911.0 498505.0 505524.0 88636.0 95053.1 123652.0 124085.0 129003.0 1047.8 1177.3 1199.9 1227.8 1236.4 17659.9 21229.9 21454.5 21789.0 22198.0 35762.0 54604.0 57843.7 58523.0 58528.1 56274.4 70655.0 77694.0 78321.4 78430.0 7181.7 8829.6 9013.9 9058.0 9141.6 13914.2 14079.4 14363.4 14469.0 14751.8 12001.6 12327.7 12333.8 12357.9 12473.4 10531.6 10778.1 10861.3 11433.0 11538.3 12033.5 12245.0 12447.4 12611.9 12677.2 8518.8 8710.0 8752.6 8906.2 8970.2 11828.2 11859.9 11954.0 12151.4 12332.6 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:6984.95 Median:3902.50 stddev:4390.25 (112.5%) stddev-sans-first:4382.53 20:16:45 INFO - PID 1647 | Values: 1402.9 1420.8 1425.9 1446.7 1492.0 11209.6 11303.4 11892.8 11994.6 12167.2 11694.4 11729.0 11732.1 11735.2 11930.3 2542.4 2550.4 2560.3 2561.4 2565.4 2438.0 2445.4 2459.1 2464.4 2523.8 2503.1 2503.2 2504.1 2515.6 2538.8 2386.0 2417.2 2420.9 2433.2 2460.0 2421.2 2515.2 2526.8 2535.0 2543.4 8145.0 11205.6 11403.4 11513.9 11834.1 6316.7 6555.9 6562.1 6628.6 6654.6 4184.1 4186.7 4267.4 4281.1 4305.5 941.5 1189.7 1239.9 1241.2 1247.7 15074.5 15557.5 16321.7 16440.0 16662.8 5252.4 5321.8 5542.5 5570.7 5670.2 11513.4 11700.7 11707.8 11932.8 12049.2 6214.4 6280.8 6298.3 6397.0 6484.2 5823.6 5860.6 5935.7 5969.5 6063.4 4359.7 4366.2 4400.7 4424.2 4461.7 1251.4 1261.9 1266.5 1277.4 1297.8 751.7 763.4 779.5 801.6 812.8 6636.7 6937.5 6948.3 6955.4 7007.6 919.2 947.8 967.5 980.8 1000.2 3835.6 3850.1 3876.3 3928.7 4064.4 14710.0 14834.9 14900.9 14939.0 15131.6 11691.9 14529.3 14787.9 14892.0 15036.4 7916.9 8459.9 8477.0 8524.8 8546.7 5959.2 6178.0 6657.3 6960.2 6999.2 9936.7 12768.9 12769.9 13093.4 13182.1 13227.3 13785.3 13821.9 13981.6 14158.7 7809.6 8846.7 9057.9 9096.2 9264.6 10471.3 10481.4 10690.3 10690.8 10794.0 8940.4 8993.2 9053.2 9150.0 9218.0 7311.6 8720.6 8841.8 8941.0 9051.2 8002.0 8070.0 8166.7 8278.0 8357.2 5945.7 5951.2 6024.7 6097.1 6116.2 5956.5 6087.8 6107.9 6108.3 6146.4 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5064.51 Median:5670.70 stddev:1633.20 (28.8%) stddev-sans-first:1599.84 20:16:45 INFO - PID 1647 | Values: 401.7 402.8 404.1 408.5 409.8 4860.7 4927.8 5948.0 5982.2 6079.5 5437.1 5658.7 5878.3 5918.7 5962.3 4021.6 4457.8 4549.4 4554.4 4600.9 3895.3 4053.8 4462.8 4488.0 4499.6 3429.8 3982.5 3984.9 4274.9 4334.8 3573.3 3796.6 3811.9 4130.7 4269.5 3012.6 3139.6 3227.4 3696.3 3708.6 4681.7 5414.2 5682.7 5781.7 5797.7 4269.9 4421.7 4605.8 4994.8 5059.6 5938.8 6248.6 6265.7 6392.2 6413.2 3521.3 4046.4 4125.2 4217.3 4296.3 5106.7 6020.8 6185.0 6650.6 6785.4 4241.5 4249.8 4283.3 4390.9 4394.0 4071.7 4336.7 4358.1 4404.8 4449.5 4757.0 4830.8 5103.9 5205.9 5235.9 5428.6 6112.5 6299.9 6339.5 6495.5 1466.0 1474.8 1484.6 1501.4 1522.8 932.6 984.6 987.7 991.6 1026.8 5300.5 5847.2 6063.5 6172.4 6216.7 6215.8 6550.9 6581.8 6702.0 6781.2 5427.9 5644.7 5794.9 6054.3 6203.2 2827.3 3227.9 3394.7 3455.7 3506.5 5556.8 6383.6 6766.5 6770.7 6808.2 5947.9 6762.2 6768.9 6773.0 6784.7 5456.6 5534.9 6117.0 6405.8 6925.7 5182.7 6367.2 6556.6 6690.8 6718.4 6346.9 6383.0 6433.9 6452.7 6540.7 6684.5 6753.5 6760.5 6911.3 6939.2 4228.4 4259.7 4277.3 4322.4 4403.8 6394.6 6516.6 6574.6 6621.4 6746.2 6366.5 6383.5 6412.1 6421.1 6545.3 5424.8 6178.8 6412.7 6490.2 6526.8 4577.5 5454.4 5884.6 6145.8 6359.9 5185.9 6293.2 6356.5 6398.3 6498.4 5558.1 6198.7 6208.9 6232.0 6376.2 20:16:45 INFO - PID 1647 | 20:16:45 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:24296.65 Median:16993.36 stddev:37559.43 (221.0%) stddev-sans-first:37623.21 20:16:45 INFO - PID 1647 | Values: 758.7 819.9 837.2 840.4 882.5 14385.3 14534.4 16251.8 16726.4 16786.9 14112.5 15931.2 17259.6 17342.0 17380.8 938.9 964.5 981.2 1009.2 1017.9 984.9 988.9 1035.7 1045.3 1051.9 1035.6 1049.2 1079.9 1085.2 1091.3 1075.9 1076.3 1077.7 1082.0 1082.0 778.2 789.6 792.5 796.2 804.3 16679.9 16978.4 17008.3 17188.2 17261.5 17075.1 17350.6 17495.7 17561.9 18131.5 13990.5 14474.2 14583.2 14848.7 15036.8 3523.8 3526.3 3542.5 3548.8 3570.4 128033.0 134404.0 134407.3 135798.0 153305.5 13537.9 13897.5 13973.2 13985.8 14431.4 26108.5 26711.9 26935.5 26971.6 27002.6 7626.0 7790.7 7918.0 7930.8 7935.0 3542.6 3622.0 3940.1 3969.8 3989.6 3864.1 4035.3 4713.9 4803.5 4874.0 787.7 915.3 943.8 945.4 947.3 505.6 635.8 646.3 669.0 677.0 10067.8 10878.5 11214.6 12119.6 12570.3 542.9 629.0 643.4 644.4 656.9 5442.9 5518.4 5562.8 5678.2 5685.0 139112.0 140719.0 140939.3 145376.3 149203.0 108816.0 112605.0 113405.0 113938.7 114671.4 83630.0 100448.5 108087.1 108653.0 110380.0 10964.2 11064.7 11085.2 11251.4 11347.8 40471.8 40844.6 45021.3 45183.0 45191.0 48060.0 48674.0 53244.0 60992.1 63038.0 12768.0 12914.9 12985.5 13224.7 13393.8 15638.2 15830.3 16059.9 16086.3 16352.3 14834.5 14893.9 14920.6 14963.6 15327.5 14711.8 16278.2 16348.9 17473.0 17931.3 17070.0 17133.7 17185.5 17282.3 17507.0 11562.5 11890.3 12375.8 12402.0 12489.8 13161.0 13464.9 13870.7 13927.2 14192.9 20:16:45 INFO - PID 1647 | -------- Summary: end -------- 20:16:45 INFO - PID 1647 | 20:16:45 INFO - TEST-INFO | 1647: exit 0 20:16:45 INFO - TEST-OK | dromaeo_css | took 1142257ms 20:16:45 INFO - TEST-START | kraken 20:16:45 INFO - operating with platform_type : mac_ 20:16:45 INFO - Initialising browser for kraken test... 20:16:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpiVcUv1/profile 20:16:45 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 20:17:18 INFO - TEST-INFO | 1654: killed by SIGTERM 20:17:18 INFO - Browser initialized. 20:17:18 INFO - Running cycle 1/1 for kraken test... 20:17:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpiVcUv1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:17:20 INFO - PID 1658 | 2017-05-25 20:17:20.736 plugin-container[1659:11179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 20:17:20 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:20 INFO - PID 1658 | 2017-05-25 20:17:20.737 plugin-container[1659:11179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 20:17:20 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:20 INFO - PID 1658 | 2017-05-25 20:17:20.737 plugin-container[1659:11179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:17:21 INFO - PID 1658 | 2017-05-25 20:17:21.474 plugin-container[1660:11224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 20:17:21 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:21 INFO - PID 1658 | 2017-05-25 20:17:21.475 plugin-container[1660:11224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990f, name = 'com.apple.CFPasteboardClient' 20:17:21 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:21 INFO - PID 1658 | 2017-05-25 20:17:21.475 plugin-container[1660:11224] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:17:30 INFO - PID 1658 | RSS: Main: 242892800 20:17:30 INFO - PID 1658 | 20:17:33 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 20:17:34 INFO - PID 1658 | RSS: Main: 256790528 20:17:34 INFO - PID 1658 | 20:17:36 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 20:17:37 INFO - PID 1658 | RSS: Main: 265797632 20:17:37 INFO - PID 1658 | 20:17:40 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 20:17:41 INFO - PID 1658 | RSS: Main: 268886016 20:17:41 INFO - PID 1658 | 20:17:43 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 20:17:44 INFO - PID 1658 | RSS: Main: 271421440 20:17:44 INFO - PID 1658 | 20:17:46 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:17:46 INFO - PID 1658 | RSS: Main: 271339520 20:17:46 INFO - PID 1658 | 20:17:49 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 20:17:50 INFO - PID 1658 | RSS: Main: 276197376 20:17:50 INFO - PID 1658 | 20:17:53 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 20:17:53 INFO - PID 1658 | RSS: Main: 278618112 20:17:53 INFO - PID 1658 | 20:17:56 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 20:17:57 INFO - PID 1658 | RSS: Main: 277929984 20:17:57 INFO - PID 1658 | 20:17:59 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:17:59 INFO - PID 1658 | RSS: Main: 277504000 20:17:59 INFO - PID 1658 | 20:18:01 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:18:02 INFO - PID 1658 | RSS: Main: 277712896 20:18:02 INFO - PID 1658 | 20:18:04 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:18:05 INFO - PID 1658 | RSS: Main: 277749760 20:18:05 INFO - PID 1658 | 20:18:07 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:18:08 INFO - PID 1658 | RSS: Main: 277372928 20:18:08 INFO - PID 1658 | 20:18:11 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:18:12 INFO - PID 1658 | RSS: Main: 278028288 20:18:12 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 20:18:14 INFO - PID 1658 | RSS: Main: 279400448 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | __start_tp_report 20:18:14 INFO - PID 1658 | _x_x_mozilla_page_load 20:18:14 INFO - PID 1658 | _x_x_mozilla_page_load_details 20:18:14 INFO - PID 1658 | |i|pagename|runs| 20:18:14 INFO - PID 1658 | |0;ai-astar;104;122;115;113;115;115;116;114;116;115 20:18:14 INFO - PID 1658 | |1;audio-beat-detection;137;110;134;196;142;111;109;109;139;117 20:18:14 INFO - PID 1658 | |2;audio-dft;202;195;195;200;201;214;201;227;240;197 20:18:14 INFO - PID 1658 | |3;audio-fft;81;60;63;80;48;64;77;83;89;88 20:18:14 INFO - PID 1658 | |4;audio-oscillator;90;98;121;104;70;69;85;69;79;109 20:18:14 INFO - PID 1658 | |5;imaging-gaussian-blur;89;89;90;88;91;88;88;89;96;89 20:18:14 INFO - PID 1658 | |6;imaging-darkroom;96;102;95;96;96;98;96;96;97;100 20:18:14 INFO - PID 1658 | |7;imaging-desaturate;137;140;93;95;93;91;92;96;94;90 20:18:14 INFO - PID 1658 | |8;json-parse-financial;51;50;49;51;52;50;54;51;52;51 20:18:14 INFO - PID 1658 | |9;json-stringify-tinderbox;58;77;85;100;66;62;56;54;56;56 20:18:14 INFO - PID 1658 | |10;stanford-crypto-aes;83;87;88;85;89;87;86;87;144;87 20:18:14 INFO - PID 1658 | |11;stanford-crypto-ccm;139;136;138;139;140;137;141;136;141;139 20:18:14 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;184;191;188;190;195;242;189;189;184;187 20:18:14 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;68;69;65;72;87;99;78;69;66;68 20:18:14 INFO - PID 1658 | __end_tp_report 20:18:14 INFO - PID 1658 | __start_cc_report 20:18:14 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1049 20:18:14 INFO - PID 1658 | __end_cc_report 20:18:14 INFO - PID 1658 | __startTimestamp1495768694281__endTimestamp 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | ------- Summary: start ------- 20:18:14 INFO - PID 1658 | Number of tests: 14 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.00 stddev:4.40 (3.8%) stddev-sans-first:2.55 20:18:14 INFO - PID 1658 | Values: 104.0 122.0 115.0 113.0 115.0 115.0 116.0 114.0 116.0 115.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:135.50 stddev:26.78 (19.8%) stddev-sans-first:28.30 20:18:14 INFO - PID 1658 | Values: 137.0 110.0 134.0 196.0 142.0 111.0 109.0 109.0 139.0 117.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:207.20 Median:201.50 stddev:15.17 (7.5%) stddev-sans-first:15.97 20:18:14 INFO - PID 1658 | Values: 202.0 195.0 195.0 200.0 201.0 214.0 201.0 227.0 240.0 197.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:73.30 Median:80.50 stddev:13.68 (17.0%) stddev-sans-first:14.22 20:18:14 INFO - PID 1658 | Values: 81.0 60.0 63.0 80.0 48.0 64.0 77.0 83.0 89.0 88.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:74.50 stddev:18.28 (24.5%) stddev-sans-first:19.38 20:18:14 INFO - PID 1658 | Values: 90.0 98.0 121.0 104.0 70.0 69.0 85.0 69.0 79.0 109.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.00 stddev:2.41 (2.7%) stddev-sans-first:2.54 20:18:14 INFO - PID 1658 | Values: 89.0 89.0 90.0 88.0 91.0 88.0 88.0 89.0 96.0 89.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.00 stddev:2.20 (2.3%) stddev-sans-first:2.29 20:18:14 INFO - PID 1658 | Values: 96.0 102.0 95.0 96.0 96.0 98.0 96.0 96.0 97.0 100.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:93.00 stddev:19.28 (20.7%) stddev-sans-first:15.78 20:18:14 INFO - PID 1658 | Values: 137.0 140.0 93.0 95.0 93.0 91.0 92.0 96.0 94.0 90.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:1.37 (2.7%) stddev-sans-first:1.45 20:18:14 INFO - PID 1658 | Values: 51.0 50.0 49.0 51.0 52.0 50.0 54.0 51.0 52.0 51.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:60.00 stddev:15.46 (25.8%) stddev-sans-first:16.05 20:18:14 INFO - PID 1658 | Values: 58.0 77.0 85.0 100.0 66.0 62.0 56.0 54.0 56.0 56.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:87.00 stddev:18.24 (21.0%) stddev-sans-first:19.03 20:18:14 INFO - PID 1658 | Values: 83.0 87.0 88.0 85.0 89.0 87.0 86.0 87.0 144.0 87.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:139.00 stddev:1.84 (1.3%) stddev-sans-first:1.94 20:18:14 INFO - PID 1658 | Values: 139.0 136.0 138.0 139.0 140.0 137.0 141.0 136.0 141.0 139.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.90 Median:189.50 stddev:17.21 (9.1%) stddev-sans-first:17.87 20:18:14 INFO - PID 1658 | Values: 184.0 191.0 188.0 190.0 195.0 242.0 189.0 189.0 184.0 187.0 20:18:14 INFO - PID 1658 | 20:18:14 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:70.50 stddev:10.96 (15.5%) stddev-sans-first:11.40 20:18:14 INFO - PID 1658 | Values: 68.0 69.0 65.0 72.0 87.0 99.0 78.0 69.0 66.0 68.0 20:18:14 INFO - PID 1658 | -------- Summary: end -------- 20:18:14 INFO - PID 1658 | 20:18:15 INFO - TEST-INFO | 1658: exit 0 20:18:15 INFO - TEST-OK | kraken | took 89554ms 20:18:15 INFO - SUITE-END | took 1231s 20:18:15 INFO - Completed test suite (00:20:32) 20:18:15 INFO - javascript benchmark 20:18:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7788.253166781557, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2986.3877905029467, 3610.2057547339577, 3632.4914511968313, 3650.908727281819, 3651.580068292147, 6119.807225076668, 6143.846403839906, 6199.938000619993, 6213.53398495113, 6246.187995560582, 6200.0, 6204.069389591552, 6218.782342618009, 6219.689015549227, 6254.874902501959, 5439.5920305977015, 5528.308961379827, 5528.474794894479, 5548.6948217848, 5555.083411237141, 5579.2746942897365, 5637.182608521762, 5645.01212287848, 5656.349519805213, 5668.319801623811, 5429.0, 5449.155380915964, 5536.058869992111, 5578.30271216098, 5618.775248990036, 5503.477169668875, 5519.751611177507, 5522.337319521643, 5546.639468434539, 5569.192467092289, 5499.202615620713, 5503.2020357048405, 5509.421510741395, 5517.117261238182, 5563.36021357541, 6178.165947597062, 6254.624722516618, 6280.434760871543, 6291.213598300213, 6296.433321001086, 6574.1124948131865, 6586.7035983380865, 6745.258021617618, 6767.830804229934, 6773.0, 5668.0, 6819.795406137774, 6844.349786770249, 6855.5543889647015, 6869.896951545681, 5471.644343117724, 6200.193974783249, 6322.620642761449, 6326.43062124411, 6360.7137678804565, 6766.357196066366, 6859.5541289816, 6940.514164008572, 6977.092977912839, 6981.82545436358, 6271.686415679289, 6432.839179020469, 6587.967060164764, 6616.305287944697, 6653.234877989094, 6562.0, 6689.46484281266, 6785.830354241183, 6792.0, 6818.693158807866, 6690.933090669128, 6707.429868461138, 6716.093327400836, 6717.832054198684, 6734.730610775612, 6354.142190804275, 6437.549371544041, 6582.275949645536, 6584.934150658432, 6661.100751398596, 5380.031594312983, 5383.892322153613, 5398.973005134949, 5400.1899715043055, 5412.729363531807, 4743.83396581118, 4746.976265118652, 4760.047990401933, 4763.3807605011125, 4774.474807771142, 6093.451589356979, 6257.968710156511, 6272.278687950853, 6316.115743795872, 6375.426211640882, 6815.965920170367, 6828.556143850634, 6833.487488438387, 6837.282085381063, 6851.48613853963, 5767.731099158178, 6336.239651241788, 6365.745370185141, 6388.680565971683, 6401.231852177769, 4696.694714843524, 5586.91619625706, 5848.4151584841175, 5855.326637436664, 5941.287045554561, 6928.44572434204, 6939.791806245821, 6957.1651401831095, 6979.057827193264, 6992.895106573507, 6784.6946887389195, 6792.524523283323, 6792.864142717217, 6807.21717002551, 6807.727690892309, 6641.136652235372, 6899.275576064641, 6929.099217101744, 6967.442604591722, 7010.579365238102, 6397.520185985976, 6602.735890564323, 6645.169353830771, 6656.966715166393, 6715.127033485615, 6791.490638202055, 6810.455163587, 6837.794866154023, 6845.760398386033, 6863.759768408082, 6340.1123842661145, 6406.90389644165, 6446.613203207822, 6513.413792758674, 6628.0, 6522.412982831568, 6571.244306904767, 6574.178227721535, 6581.605103693793, 6602.966985165043, 5425.457454254543, 5859.44335288147, 5869.647821130746, 6453.290138084901, 6459.063435801689, 5114.74426278692, 6297.2128483939505, 6542.051402546509, 6544.44372228366, 6581.07864898905, 6305.716242768995, 6345.302016778243, 6375.394337537927, 6386.680665966776, 6396.936030639634], "value": 6117.965533397832, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.8479919216965, 768.4774353439659, 813.069035953833, 1229.2562999385366, 1285.3059347952105, 39746.40380394289, 40080.79959600201, 40489.76081913447, 40706.77879663608, 40936.590634093685, 40025.0, 40358.39462408066, 40675.79662101685, 40684.79657601708, 41200.79399603005, 7903.0516338039515, 8060.476069055522, 8142.43002989791, 8259.256666899977, 8296.792580185513, 6811.557248778839, 8330.125336839605, 8351.749447516553, 8387.454815437008, 8492.872606910902, 8261.793455163608, 8286.58567071647, 8383.329333653293, 8407.159284071575, 8514.957425212866, 8234.300084492825, 8283.29591984682, 8292.29515491181, 8371.246587807127, 8432.620532076071, 8046.0, 8073.354131669453, 8104.756857294232, 8247.670093196264, 8280.50316980977, 35247.471287930704, 35848.0, 35934.10164745876, 36041.45937810935, 36179.4573081404, 3438.6561343865665, 3481.721462283011, 3494.30113977203, 3574.3566158091535, 3589.766665166782, 29575.408491830258, 30346.54480182799, 30836.84581577078, 30903.690963090306, 30909.381812363852, 3028.818270903753, 3050.877964881402, 3108.4094022135946, 3121.7502599792188, 3153.6530981592005, 231890.0, 239321.60678393167, 272169.63915180496, 289771.0, 302063.97936020576, 47270.76364618224, 47516.52483475121, 48266.0, 48314.75842620764, 48426.75786621044, 48217.0, 49773.75113124411, 49921.505494615754, 50253.497465024884, 50503.49496505062, 13991.86008139926, 14128.434862605587, 14140.575782726535, 14270.3578338975, 14399.97600503906, 11323.773524529463, 11411.885881141083, 11426.885731142582, 11707.180497365103, 11754.70613234676, 10672.62645807393, 10694.572217111228, 13240.271785051915, 13567.796483052762, 13585.388657510435, 6014.699265036731, 6034.939650603438, 6039.698015099315, 6154.692265386713, 6230.320961986299, 971.4365667912593, 1005.2862467647906, 1007.4207330784828, 1053.3942982784777, 1074.398336931321, 23814.761852381256, 24548.509029819303, 24632.507349853262, 24682.629760553606, 24696.25911222666, 1095.3811096730321, 1125.4654039331251, 1131.8924702153447, 1155.8035134027236, 1157.5311998640395, 11856.22934509254, 12335.568255111208, 12378.257304561756, 12581.874181258254, 12676.80984785229, 308816.0, 310479.0, 317307.0, 318291.0, 319623.0, 43925.78037109794, 44076.77961610172, 53867.19199212015, 54305.91388172215, 55112.0, 1483.8961272711022, 1559.3606621285446, 1568.3021055630363, 1574.842515748393, 1592.1163754116576, 18792.812071879653, 23558.93984770655, 23636.409089772893, 23724.644130338405, 23985.0, 36985.445218321205, 37855.62144378631, 38073.85778426652, 38155.046123847125, 38273.0, 256913.0, 264495.0, 264612.0, 264903.0, 265761.0, 33603.49594756032, 34157.48763768496, 34214.0, 34566.30867382588, 34823.4776478348, 42931.70682931682, 46195.0, 46484.30273545832, 46699.0, 46821.82945426391, 29403.85298073496, 29942.401151976403, 30897.22756931005, 32351.514727278638, 32743.345133097682, 25323.48059116459, 28507.572386413805, 31294.0, 31413.05760827179, 31939.0, 36488.0, 36891.07772305606, 36932.26135477329, 38127.0, 38684.6131538681, 30351.0, 31478.370432591677, 31579.578918948242, 32059.519107212946, 32318.779563634376, 20902.790972090086, 21277.89361053247, 24056.27831165068, 24556.0, 24814.751852481244], "value": 17367.66380240032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.83377066058927, 427.1307888447011, 439.54507085166847, 445.897443587975, 453.75950746104564, 17437.0, 18751.718724219132, 19290.9389983551, 20664.380068597966, 21716.457088572795, 18677.719834202464, 22528.66207006892, 22608.773912260873, 22639.773602263973, 22736.886315568398, 5429.429909859457, 5604.159376093598, 5802.82591522255, 5854.5023672987845, 5941.554383421239, 5754.16564598133, 5774.729559496904, 5861.120831875211, 5896.027155519356, 5936.436038576328, 5780.1907732917425, 5790.68151251681, 5809.0, 5885.264341957255, 5951.345352011275, 4623.42207224097, 5769.5672824538115, 5818.5345172386105, 5929.14027466015, 5934.376890426508, 5196.168613021899, 5197.610179236534, 5256.842294731164, 5362.973185134088, 5395.7032363220005, 26974.325641858915, 27175.592366114524, 27507.174784756287, 27664.723352766418, 27770.028049018187, 2117.047328702075, 2150.956980860374, 2158.438805910459, 2160.459885028743, 2170.0343347210496, 13241.73516529664, 17512.474625761246, 17740.46778596644, 18034.549136271566, 18328.725069123975, 2090.446031801574, 2112.4402033461147, 2123.766385697572, 2143.389134096775, 2178.6949827024227, 111724.0, 152138.23930880387, 155183.44816551803, 158799.20600396924, 163615.0, 12098.274103553815, 12366.258024518558, 12770.169938953939, 12786.29675367845, 12797.36013199355, 15803.20983950798, 15873.04761714301, 15929.442469513511, 16136.757948630546, 16204.0, 8828.823423531494, 8882.86675699865, 8890.73327800154, 8925.419847709878, 8974.551272436353, 8411.0, 8574.914250857411, 8652.826943461096, 8656.95671521651, 8842.0, 7761.495502792413, 7850.175731548105, 7859.292663660183, 8051.557164356017, 8074.273315401642, 2103.463616777712, 2128.4572434029533, 2130.616489031989, 2203.504211552388, 2329.7670232976566, 847.2544161138283, 920.8940971788196, 921.4563407589554, 942.4439580647314, 958.3195930889146, 18795.436136915916, 19719.40841774749, 20364.389068327975, 20859.58280834375, 20876.895615521826, 803.658445160816, 885.2386947225381, 955.3694561589317, 979.2313034268066, 1008.6469735592631, 10525.9473702631, 10575.36547807134, 10651.0, 10709.410982395888, 10743.89256107429, 415553.0, 451921.0, 492911.0, 498505.0, 505524.0, 88636.0, 95053.14840554794, 123652.0, 124085.0, 129003.0, 1047.8218702820539, 1177.2877409167331, 1199.9160058795976, 1227.7851376009162, 1236.4436003798146, 17659.940403575827, 21229.893850530647, 21454.463638409165, 21789.0, 22198.0, 35762.0, 54604.0, 57843.71078144582, 58523.0, 58528.12207816971, 56274.43725562692, 70655.0, 77694.0, 78321.4335713294, 78430.0, 7181.748638797617, 8829.646814127364, 9013.864792027995, 9057.954710226406, 9141.63433462654, 13914.234717090658, 14079.366428510537, 14363.353649086026, 14469.0, 14751.778723318945, 12001.63995080149, 12327.69180770488, 12333.753324933272, 12357.93821030889, 12473.376331183223, 10531.631392901212, 10778.13774898022, 10861.294015888941, 11433.006462783185, 11538.307701537598, 12033.518659253532, 12245.0, 12447.439865205906, 12611.927986121282, 12677.239365638092, 8518.78703032429, 8710.0, 8752.64989400417, 8906.198442140238, 8970.237529809778, 11828.17202795796, 11859.932606065495, 11954.0, 12151.4203153593, 12332.568360107352], "value": 10169.861770490432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.9368678409471, 1420.7584710599197, 1425.921574313413, 1446.6817300193954, 1491.9925400372997, 11209.551617935273, 11303.434828258596, 11892.82160767587, 11994.64016079518, 12167.209131406464, 11694.356810375426, 11729.006068968274, 11732.120090993167, 11735.178537502377, 11930.343831089287, 2542.377117606189, 2550.4006558458746, 2560.2703229579574, 2561.4108754986364, 2565.384307766132, 2437.9512409751796, 2445.4375493636385, 2459.077845807822, 2464.383904023994, 2523.7980961523126, 2503.0613519930025, 2503.173952595648, 2504.0860086068546, 2515.610080437535, 2538.7715105640486, 2385.964210536835, 2417.2385698504913, 2420.9394765130837, 2433.221369161869, 2459.9631005534843, 2421.2373102472666, 2515.157422263544, 2526.7978561715204, 2534.974650253492, 2543.3514453814346, 8144.959275203645, 11205.551777928873, 11403.3728144952, 11513.942430287878, 11834.05327573792, 6316.652584107872, 6555.901661475082, 6562.1469209003, 6628.569143005738, 6654.567453115532, 4184.100418410026, 4186.665066794649, 4267.359896015591, 4281.143771245764, 4305.50486694031, 941.4586612697726, 1189.6966273600262, 1239.900807935372, 1241.2428418664608, 1247.7442124364484, 15074.472393466176, 15557.45548905783, 16321.673566528721, 16440.013599184087, 16662.83337166625, 5252.369715634149, 5321.787128514893, 5542.501174894279, 5570.716006437422, 5670.234518340054, 11513.424328783527, 11700.70748231301, 11707.824382634268, 11932.821007684892, 12049.216800907912, 6214.4407003369915, 6280.780172693934, 6298.307186209513, 6397.0, 6484.157059582224, 5823.621464604787, 5860.619059761188, 5935.6735379553675, 5969.522438204933, 6063.363346848518, 4359.716618419792, 4366.214081465367, 4400.669949753781, 4424.203643344164, 4461.665375096816, 1251.4055823483955, 1261.861195268538, 1266.468083404972, 1277.4032459425719, 1297.7599144158362, 751.6993202718957, 763.3587786259569, 779.4660657449665, 801.6352559585491, 812.8252425728497, 6636.668166591748, 6937.54905931123, 6948.305169483011, 6955.374016338554, 7007.579545227302, 919.1891972813546, 947.8009617980164, 967.4775621164492, 980.8479685648736, 1000.2248257600418, 3835.5589107252463, 3850.133719912997, 3876.341022026262, 3928.6660633846445, 4064.4309796628495, 14710.0, 14834.851651483563, 14900.925495372454, 14939.028963117362, 15131.621709457351, 11691.88308116925, 14529.273536323353, 14787.92606036963, 14892.0320179188, 15036.398544058115, 7916.881246781303, 8459.915400845914, 8477.0, 8524.829503410021, 8546.658133674708, 5959.1657167995645, 6178.0, 6657.2677005528385, 6960.164780226405, 6999.160100787938, 9936.652217172365, 12768.936155319536, 12769.872301276868, 13093.410796513988, 13182.077254592086, 13227.272500012592, 13785.310734463437, 13821.861781382058, 13981.580552583438, 14158.716825663223, 7809.648565814438, 8846.690365837165, 9057.909420905706, 9096.226820720312, 9264.629414823332, 10471.3193642413, 10481.42352170638, 10690.305130166515, 10690.83963740529, 10794.028537431668, 8940.374173808032, 8993.235574976201, 9053.230475409664, 9150.0, 9217.953910230406, 7311.634418278958, 8720.607572659372, 8841.823163536821, 8940.955295223483, 9051.230645395215, 8002.0, 8069.959650201711, 8166.714165004197, 8277.95861020691, 8357.247847693738, 5945.732442040032, 5951.226340575698, 6024.66864322449, 6097.115918191926, 6116.17431646722, 5956.463918247206, 6087.786927457518, 6107.8778424430375, 6108.297545782292, 6146.354632763496], "value": 5311.628076540785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.66862338570684, 402.7764590652188, 404.1351507773367, 408.54447291270236, 409.83606557377067, 4860.684055536388, 4927.778249978751, 5948.0, 5982.248165843712, 6079.4528492435675, 5437.075697131488, 5658.745356458959, 5878.294604647448, 5918.721292302473, 5962.25471816023, 4021.557628660852, 4457.799399027052, 4549.408576885, 4554.43069616298, 4600.930986035212, 3895.2793733159315, 4053.756774593534, 4462.843800466984, 4488.0, 4499.572540608653, 3429.7599168058246, 3982.5220973483224, 3984.9003774905714, 4274.871753847374, 4334.804933777972, 3573.3389322975204, 3796.563395209531, 3811.866584669537, 4130.669546436278, 4269.509006464265, 3012.638483381986, 3139.587185766414, 3227.3706627207703, 3696.31618150643, 3708.609271523179, 4681.665725268316, 5414.214938833887, 5682.658301196861, 5781.682007489587, 5797.681127537984, 4269.914601707979, 4421.6904816662845, 4605.815767369301, 4994.800207991676, 5059.595232381401, 5938.792142275043, 6248.593841400295, 6265.686715664198, 6392.169018027673, 6413.16628838248, 3521.29574085184, 4046.3728122141115, 4125.195586860564, 4217.34631132175, 4296.312589985588, 5106.744662766847, 6020.819375418744, 6184.96907515464, 6650.600963942131, 6785.389314961628, 4241.533431322552, 4249.80875860587, 4283.336082907154, 4390.934135987963, 4394.033312671208, 4071.6539094176733, 4336.674749393808, 4358.060663004996, 4404.8458303959205, 4449.532799056117, 4757.048590281957, 4830.830920917751, 5103.897922041612, 5205.895882082413, 5235.947640523622, 5428.6199966002005, 6112.51099912006, 6299.858048263613, 6339.492840572743, 6495.545311828221, 1465.992670036643, 1474.7640377539542, 1484.5546336099128, 1501.3919362658141, 1522.7715842623695, 932.6036434515304, 984.5527075199445, 987.6790043235977, 991.5637119667323, 1026.8408396698524, 5300.501668898509, 5847.239858818326, 6063.453349593182, 6172.444479996732, 6216.720247588869, 6215.782447614311, 6550.853600619873, 6581.782370261517, 6701.966490167518, 6781.220159681699, 5427.9185812212845, 5644.701718604661, 5794.855128621818, 6054.334023257438, 6203.16257305267, 2827.3355761396056, 3227.9186472531965, 3394.7114495267683, 3455.7235421166747, 3506.474028895635, 5556.777728890799, 6383.616982981036, 6766.526343156031, 6770.729170833111, 6808.183018037867, 5947.940520594825, 6762.188537375448, 6768.864622707519, 6772.966135169293, 6784.660766961632, 5456.61803673739, 5534.916976245359, 6117.0, 6405.839854003594, 6925.722971081201, 5182.689038657692, 6367.204099487564, 6556.573822701509, 6690.832729181615, 6718.428933540704, 6346.90479642815, 6382.968085159545, 6433.906235939995, 6452.709458108303, 6540.738370465128, 6684.532082754161, 6753.493487988322, 6760.459163266829, 6911.274316196727, 6939.236683964659, 4228.386883901756, 4259.701820872509, 4277.294246449301, 4322.416473776126, 4403.8458653946955, 6394.5523813334485, 6516.57642253252, 6574.572652777554, 6621.410861393328, 6746.245976046362, 6366.458850997532, 6383.489320854228, 6412.070249813844, 6421.133147025092, 6545.345465453416, 5424.83725488236, 6178.783742568989, 6412.711427985845, 6490.156279683611, 6526.771562995463, 4577.519360467103, 5454.400015998316, 5884.617499862496, 6145.754169833335, 6359.90460143089, 5185.896282074413, 6293.244810622755, 6356.491480681627, 6398.264199617103, 6498.447631951337, 5558.110702287615, 6198.72105755233, 6208.937910620836, 6232.039781103939, 6376.234851817811], "value": 4567.33777152397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.7496126278327, 819.9057108432525, 837.2464781696476, 840.4159109418954, 882.5349919134235, 14385.280735963212, 14534.418623255084, 16251.75622365664, 16726.41457548986, 16786.91606541967, 14112.506062287823, 15931.20343982802, 17259.568510787238, 17342.046187459684, 17380.82619173808, 938.9014153513886, 964.4599024546277, 981.1807141037242, 1009.2178561614734, 1017.9389236645825, 984.916282116021, 988.85661579071, 1035.6892932120334, 1045.29442626227, 1051.8527406163143, 1035.6012935020033, 1049.1816383221062, 1079.9460026998618, 1085.2240647936724, 1091.2797553614616, 1075.9031687148195, 1076.34343050739, 1077.7467295185625, 1081.9891801081967, 1081.9891801082006, 778.1790211327047, 789.5894135049742, 792.4967645545063, 796.2117503671324, 804.252045597594, 16679.91660041704, 16978.405755798613, 17008.319667213298, 17188.226529806063, 17261.482155535356, 17075.060871652055, 17350.56623584408, 17495.737563936553, 17561.912190438965, 18131.54671133219, 13990.510332138429, 14474.203918784462, 14583.197924114169, 14848.703025939529, 15036.849631503765, 3523.7885726856216, 3526.312369088029, 3542.468629705539, 3548.7515873888838, 3570.357335679577, 128033.0, 134404.0, 134407.32796336053, 135797.96303055462, 153305.46694533134, 13537.864621353661, 13897.513587024405, 13973.161610303414, 13985.790213146607, 14431.35058922351, 26108.477830443284, 26711.866440667673, 26935.461290774074, 26971.595426068623, 27002.5949610756, 7625.961870190614, 7790.727324543614, 7918.049834019955, 7930.841383172305, 7934.96845410093, 3542.6006727342556, 3621.9637803622154, 3940.113474468279, 3969.801509924492, 3989.6209860063245, 3864.14988702485, 4035.3139966205817, 4713.882152946135, 4803.519648035239, 4873.951260487421, 787.6652422720435, 915.2586405011962, 943.7546237978175, 945.3713280668416, 947.3179310896281, 505.5677395826589, 635.758411803521, 646.3181343682367, 668.9998452313822, 676.9541059063758, 10067.798644027078, 10878.510467029002, 11214.607488737855, 12119.636410907688, 12570.308633025088, 542.8751387180891, 628.9716962736687, 643.3919945651314, 644.4168027934727, 656.9277379488252, 5442.91835622466, 5518.44815518453, 5562.805301814417, 5678.176664383641, 5684.971575142098, 139112.0, 140719.0, 140939.2953035228, 145376.27311863372, 149203.0, 108816.0, 112605.01683266085, 113405.0, 113938.72122557502, 114671.42664286793, 83630.0, 100448.4977575122, 108087.13607887663, 108653.0, 110380.0, 10964.177686673373, 11064.668059958214, 11085.224034317844, 11251.381174035507, 11347.773044539228, 40471.79764101278, 40844.59155408408, 45021.32468013048, 45183.0, 45191.0, 48060.0, 48674.0, 53244.0, 60992.08511872237, 63038.0, 12768.0, 12914.935425323189, 12985.480580776662, 13224.669383265495, 13393.799093013808, 15638.218089095728, 15830.28763705613, 16059.919700401424, 16086.276117574504, 16352.345906164097, 14834.480793172188, 14893.851061489246, 14920.62698432548, 14963.625909352353, 15327.46353877609, 14711.779323310373, 16278.186090695655, 16348.918255408647, 17473.038982855556, 17931.282748689908, 17069.975801451954, 17133.65732685364, 17185.484435466456, 17282.308707652057, 17507.0, 11562.537498499965, 11890.286582804712, 12375.75248495043, 12402.007839372649, 12489.812652810397, 13161.0, 13464.922806175678, 13870.722585548434, 13927.164370137823, 14192.92903535517], "value": 8512.440974038873, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 122.0, 115.0, 113.0, 115.0, 115.0, 116.0, 114.0, 116.0, 115.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 110.0, 134.0, 196.0, 142.0, 111.0, 109.0, 109.0, 139.0, 117.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 195.0, 195.0, 200.0, 201.0, 214.0, 201.0, 227.0, 240.0, 197.0], "value": 207.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 63.0, 80.0, 48.0, 64.0, 77.0, 83.0, 89.0, 88.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 121.0, 104.0, 70.0, 69.0, 85.0, 69.0, 79.0, 109.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 88.0, 91.0, 88.0, 88.0, 89.0, 96.0, 89.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 102.0, 95.0, 96.0, 96.0, 98.0, 96.0, 96.0, 97.0, 100.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 93.0, 95.0, 93.0, 91.0, 92.0, 96.0, 94.0, 90.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 51.0, 52.0, 50.0, 54.0, 51.0, 52.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 77.0, 85.0, 100.0, 66.0, 62.0, 56.0, 54.0, 56.0, 56.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 88.0, 85.0, 89.0, 87.0, 86.0, 87.0, 144.0, 87.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 138.0, 139.0, 140.0, 137.0, 141.0, 136.0, 141.0, 139.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 191.0, 188.0, 190.0, 195.0, 242.0, 189.0, 189.0, 184.0, 187.0], "value": 193.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 65.0, 72.0, 87.0, 99.0, 78.0, 69.0, 66.0, 68.0], "value": 74.1, "unit": "score"}]}]} 20:18:15 INFO - Return code: 0 20:18:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:18:15 INFO - # TBPL SUCCESS # 20:18:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:18:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:18:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:18:15 INFO - 2017-05-25 20:18:15.629 screenresolution[1665:11602] starting screenresolution argv=screenresolution get 20:18:15 INFO - 2017-05-25 20:18:15.634 screenresolution[1665:11602] Display 0: 1600x1200x32@60 20:18:15 INFO - 2017-05-25 20:18:15.640 screenresolution[1666:11605] starting screenresolution argv=screenresolution list 20:18:15 INFO - Available Modes on Display 0 20:18:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:18:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:18:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:18:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:18:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:18:15 INFO - Intel Iris: 20:18:15 INFO - Chipset Model: Intel Iris 20:18:15 INFO - Type: GPU 20:18:15 INFO - Bus: Built-In 20:18:15 INFO - VRAM (Dynamic, Max): 1536 MB 20:18:15 INFO - Vendor: Intel (0x8086) 20:18:15 INFO - Device ID: 0x0a2e 20:18:15 INFO - Revision ID: 0x0009 20:18:15 INFO - Displays: 20:18:15 INFO - Display: 20:18:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:18:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:18:15 INFO - Display Serial Number: 1600x1200 60 20:18:15 INFO - Main Display: Yes 20:18:15 INFO - Mirror: Off 20:18:15 INFO - Online: Yes 20:18:15 INFO - Rotation: Supported 20:18:15 INFO - Return code: 0 20:18:15 INFO - Running post-action listener: _resource_record_post_action 20:18:15 INFO - [mozharness: 2017-05-26 03:18:15.879904Z] Finished run-tests step (success) 20:18:15 INFO - Running post-run listener: _resource_record_post_run 20:18:15 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 34369024; Write bytes: 568233472; Read time: 238; Write time: 1809 20:18:15 INFO - TinderboxPrint: CPU usage
53.4% 20:18:15 INFO - TinderboxPrint: I/O read bytes / time
34,369,024 / 238 20:18:15 INFO - TinderboxPrint: I/O write bytes / time
568,233,472 / 1,809 20:18:15 INFO - TinderboxPrint: CPU idle
2,335.8 (46.6%) 20:18:15 INFO - TinderboxPrint: CPU system
1,035.0 (20.6%) 20:18:15 INFO - TinderboxPrint: CPU user
1,646.9 (32.8%) 20:18:15 INFO - TinderboxPrint: Swap in / out
274,018,304 / 0 20:18:15 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 235358720; Write bytes: 230168576; Read time: 16941; Write time: 507 20:18:15 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 32230912; Write bytes: 319788544; Read time: 217; Write time: 1258 20:18:16 INFO - Running post-run listener: _upload_blobber_files 20:18:16 INFO - Blob upload gear active. 20:18:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:18:16 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 20:18:16 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'] 20:18:16 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 20:18:16 INFO - (blobuploader) - INFO - Open directory for files ... 20:18:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:18:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:18:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:18:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:18:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:18:17 INFO - (blobuploader) - INFO - Done attempting. 20:18:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:18:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:18:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:18:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:18:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:18:18 INFO - (blobuploader) - INFO - Done attempting. 20:18:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:18:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:18:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:18:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:18:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:18:19 INFO - (blobuploader) - INFO - Done attempting. 20:18:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:18:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:18:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:18:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:18:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:18:21 INFO - (blobuploader) - INFO - Done attempting. 20:18:21 INFO - (blobuploader) - INFO - Iteration through files over. 20:18:21 INFO - Return code: 0 20:18:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:18:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:18:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b55ebf7c0e73b75a94121d057f508b8a9e27fb7afc144c39484220c4ead64d5e63b9f5bff10f5b84ce06358f529e3c6633260251e491297319f894b2510aaaf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/891063ecf8b53713eac33ced0194731f135fbdbafbb083dade269f095a8903682504501f32d924a017673f7d768621aab97a84612cb42807bd43de652026cf9b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbfeff60795c34d86f3f9585ad5d3a4fd2e433bed86eec406e7bd5c328e13fd36a619a336e86f0e614f07f4bf185819503e7a43407bfb3c832d6b3d90b9dfd98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b61def30cb94f902e7d28d99b7db25041f899898565666d4b57b23f62cf07fb968be0f6cc3e92320c0ca9d826c92d026e8ff57f155777d3c024284c6f42b2c7"} 20:18:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:18:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:18:21 INFO - Contents: 20:18:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b55ebf7c0e73b75a94121d057f508b8a9e27fb7afc144c39484220c4ead64d5e63b9f5bff10f5b84ce06358f529e3c6633260251e491297319f894b2510aaaf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/891063ecf8b53713eac33ced0194731f135fbdbafbb083dade269f095a8903682504501f32d924a017673f7d768621aab97a84612cb42807bd43de652026cf9b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbfeff60795c34d86f3f9585ad5d3a4fd2e433bed86eec406e7bd5c328e13fd36a619a336e86f0e614f07f4bf185819503e7a43407bfb3c832d6b3d90b9dfd98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b61def30cb94f902e7d28d99b7db25041f899898565666d4b57b23f62cf07fb968be0f6cc3e92320c0ca9d826c92d026e8ff57f155777d3c024284c6f42b2c7"} 20:18:21 INFO - Running post-run listener: copy_logs_to_upload_dir 20:18:21 INFO - Copying logs to upload dir... 20:18:21 INFO - mkdir: /builds/slave/test/build/upload/logs 20:18:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.430413 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-25 20:18:21.805969) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 20:18:21.809351) ========= 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.FklG4k30dl/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.tKoahAvQ5Z/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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/0b55ebf7c0e73b75a94121d057f508b8a9e27fb7afc144c39484220c4ead64d5e63b9f5bff10f5b84ce06358f529e3c6633260251e491297319f894b2510aaaf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/891063ecf8b53713eac33ced0194731f135fbdbafbb083dade269f095a8903682504501f32d924a017673f7d768621aab97a84612cb42807bd43de652026cf9b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbfeff60795c34d86f3f9585ad5d3a4fd2e433bed86eec406e7bd5c328e13fd36a619a336e86f0e614f07f4bf185819503e7a43407bfb3c832d6b3d90b9dfd98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b61def30cb94f902e7d28d99b7db25041f899898565666d4b57b23f62cf07fb968be0f6cc3e92320c0ca9d826c92d026e8ff57f155777d3c024284c6f42b2c7"} build_url:https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/E1QJntZ_S7ePk9jyz8ku_g/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/0b55ebf7c0e73b75a94121d057f508b8a9e27fb7afc144c39484220c4ead64d5e63b9f5bff10f5b84ce06358f529e3c6633260251e491297319f894b2510aaaf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/891063ecf8b53713eac33ced0194731f135fbdbafbb083dade269f095a8903682504501f32d924a017673f7d768621aab97a84612cb42807bd43de652026cf9b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbfeff60795c34d86f3f9585ad5d3a4fd2e433bed86eec406e7bd5c328e13fd36a619a336e86f0e614f07f4bf185819503e7a43407bfb3c832d6b3d90b9dfd98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b61def30cb94f902e7d28d99b7db25041f899898565666d4b57b23f62cf07fb968be0f6cc3e92320c0ca9d826c92d026e8ff57f155777d3c024284c6f42b2c7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 20:18:21.842580) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:18:21.842911) ========= 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.FklG4k30dl/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.tKoahAvQ5Z/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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.005204 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:18:21.891620) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 20:18:21.891940) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 20:18:21.892257) ========= ========= Total master_lag: 0.17 =========