builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0224 starttime: 1496227014.21 results: success (0) revision: 06d83f260f410f125847e95618b6f8b9e83e9a2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.206190) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.206586) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.206860) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.237688) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.237941) ========= 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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-31 03:36:54-- 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% 55.6M=0s 2017-05-31 03:36:54 (55.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154038 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.414598) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.414876) ========= 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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.026712 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:54.466337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-31 03:36:54.467029) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 06d83f260f410f125847e95618b6f8b9e83e9a2f --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 06d83f260f410f125847e95618b6f8b9e83e9a2f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-31 03:36:54,561 truncating revision to first 12 chars 2017-05-31 03:36:54,561 Setting DEBUG logging. 2017-05-31 03:36:54,561 attempt 1/10 2017-05-31 03:36:54,561 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/06d83f260f41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 03:36:55,257 unpacking tar archive at: mozilla-central-06d83f260f41/testing/mozharness/ program finished with exit code 0 elapsedTime=0.977645 ========= master_lag: 2.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-31 03:36:57.968390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:57.968659) ========= script_repo_revision: 06d83f260f410f125847e95618b6f8b9e83e9a2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:57.972023) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:57.972301) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 03:36:58.040451) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-31 03:36:58.040741) ========= /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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:36:58 INFO - MultiFileLogger online at 20170531 03:36:58 in /builds/slave/test 03:36:58 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 03:36:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:36:58 INFO - {'append_to_log': False, 03:36:58 INFO - 'base_work_dir': '/builds/slave/test', 03:36:58 INFO - 'blob_upload_branch': 'Firefox', 03:36:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:36:58 INFO - 'branch': 'Firefox', 03:36:58 INFO - 'buildbot_json_path': 'buildprops.json', 03:36:58 INFO - 'config_files': ('talos/mac_config.py',), 03:36:58 INFO - 'default_actions': ('clobber', 03:36:58 INFO - 'read-buildbot-config', 03:36:58 INFO - 'download-and-extract', 03:36:58 INFO - 'populate-webroot', 03:36:58 INFO - 'create-virtualenv', 03:36:58 INFO - 'install', 03:36:58 INFO - 'setup-mitmproxy', 03:36:58 INFO - 'run-tests'), 03:36:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:36:58 INFO - 'download_minidump_stackwalk': True, 03:36:58 INFO - 'download_symbols': 'ondemand', 03:36:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:36:58 INFO - 'tooltool.py': '/tools/tooltool.py', 03:36:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:36:58 INFO - '/tools/misc-python/virtualenv.py')}, 03:36:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:36:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:36:58 INFO - 'gecko_profile': False, 03:36:58 INFO - 'gecko_profile_interval': 0, 03:36:58 INFO - 'installer_path': 'installer.exe', 03:36:58 INFO - 'log_level': 'info', 03:36:58 INFO - 'log_name': 'talos', 03:36:58 INFO - 'log_to_console': True, 03:36:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:36:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:36:58 INFO - 'opt_config_files': (), 03:36:58 INFO - 'pip_index': False, 03:36:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:36:58 INFO - 'cmd': ('bash', 03:36:58 INFO - '-c', 03:36:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:36:58 INFO - 'enabled': True, 03:36:58 INFO - 'halt_on_failure': False, 03:36:58 INFO - 'name': 'check_screen_resolution'},), 03:36:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:36:58 INFO - 'cmd': ('bash', 03:36:58 INFO - '-c', 03:36:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:36:58 INFO - 'enabled': True, 03:36:58 INFO - 'halt_on_failure': False, 03:36:58 INFO - 'name': 'check_screen_resolution'},), 03:36:58 INFO - 'run_cmd_checks_enabled': True, 03:36:58 INFO - 'suite': 'dromaeojs-e10s', 03:36:58 INFO - 'system_bits': '32', 03:36:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:36:58 INFO - 'title': 't-yosemite-r7-0224', 03:36:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:36:58 INFO - 'use_talos_json': True, 03:36:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:36:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:36:58 INFO - 'work_dir': 'build'} 03:36:58 INFO - [mozharness: 2017-05-31 10:36:58.246611Z] Running clobber step. 03:36:58 INFO - Running pre-action listener: _resource_record_pre_action 03:36:58 INFO - Running main action method: clobber 03:36:58 INFO - rmtree: /builds/slave/test/build 03:36:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:37:00 INFO - Running post-action listener: _resource_record_post_action 03:37:00 INFO - [mozharness: 2017-05-31 10:37:00.810022Z] Finished clobber step (success) 03:37:00 INFO - [mozharness: 2017-05-31 10:37:00.810182Z] Running read-buildbot-config step. 03:37:00 INFO - Running pre-action listener: _resource_record_pre_action 03:37:00 INFO - Running main action method: read_buildbot_config 03:37:00 INFO - Using buildbot properties: 03:37:00 INFO - { 03:37:00 INFO - "project": "", 03:37:00 INFO - "product": "firefox", 03:37:00 INFO - "who": "cbook@mozilla.com", 03:37:00 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:37:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:37:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:37:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:37:00 INFO - "stage_platform": "macosx64", 03:37:00 INFO - "basedir": "/builds/slave/test", 03:37:00 INFO - "buildnumber": 90, 03:37:00 INFO - "platform": "yosemite_r7", 03:37:00 INFO - "parent_task_id": "DYJrgKksRJCsy-V8NQyRFg", 03:37:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:37:00 INFO - "slavebuilddir": "test", 03:37:00 INFO - "taskId": "U-2vLu6gSjSqpFP5ZfeYDg", 03:37:00 INFO - "branch": "mozilla-central", 03:37:00 INFO - "script_repo_revision": "production", 03:37:00 INFO - "revision": "06d83f260f410f125847e95618b6f8b9e83e9a2f", 03:37:00 INFO - "slavename": "t-yosemite-r7-0224", 03:37:00 INFO - "repo_path": "mozilla-central" 03:37:00 INFO - } 03:37:00 INFO - Finding installer, test and symbols from parent task. 03:37:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-2vLu6gSjSqpFP5ZfeYDg'}, attempt #1 03:37:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-2vLu6gSjSqpFP5ZfeYDg'}, attempt #1 03:37:02 INFO - Task dependencies: LC_nNNF0QuSS81uGWvZITQ DYJrgKksRJCsy-V8NQyRFg 03:37:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg'}, attempt #1 03:37:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:02 INFO - Set test_url: https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/test_packages.json 03:37:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/target.crashreporter-symbols.zip 03:37:02 INFO - Running post-action listener: _resource_record_post_action 03:37:02 INFO - [mozharness: 2017-05-31 10:37:02.507139Z] Finished read-buildbot-config step (success) 03:37:02 INFO - [mozharness: 2017-05-31 10:37:02.507287Z] Running download-and-extract step. 03:37:02 INFO - Running pre-action listener: _resource_record_pre_action 03:37:02 INFO - Running main action method: download_and_extract 03:37:02 INFO - mkdir: /builds/slave/test/build/tests 03:37: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')]} 03:37:02 INFO - trying https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:37:02 INFO - Downloading https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/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 03:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/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 03:37:03 INFO - Downloaded 1325 bytes. 03:37:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:37:03 INFO - Using the following test package requirements: 03:37:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:37:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:37:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:37:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:37:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'jsshell-mac.zip'], 03:37:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:37:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:37:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:37:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:37:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:37:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:37:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:37:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:37:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:37:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:37:03 INFO - Fetch https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:37:05 INFO - Content-Length response header: 57628646 03:37:05 INFO - Bytes received: 57628646 03:37:23 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 03:37:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:37:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:37:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:37:25 INFO - Content-Length response header: 57628646 03:37:25 INFO - Bytes received: 57628646 03:37:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:37:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:37:41 INFO - Fetch https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:37:42 INFO - Content-Length response header: 14447425 03:37:42 INFO - Bytes received: 14447425 03:37:43 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')]} 03:37:43 INFO - trying https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:43 INFO - Downloading https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:37:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/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 03:37:44 INFO - Downloaded 67881324 bytes. 03:37:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:44 INFO - mkdir: /builds/slave/test/properties 03:37:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:37:44 INFO - Writing to file /builds/slave/test/properties/build_url 03:37:44 INFO - Contents: 03:37:44 INFO - build_url:https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:44 INFO - Running post-action listener: _resource_record_post_action 03:37:44 INFO - Running post-action listener: set_extra_try_arguments 03:37:44 INFO - [mozharness: 2017-05-31 10:37:44.972332Z] Finished download-and-extract step (success) 03:37:44 INFO - [mozharness: 2017-05-31 10:37:44.972492Z] Running populate-webroot step. 03:37:44 INFO - Running pre-action listener: _resource_record_pre_action 03:37:44 INFO - Running main action method: populate_webroot 03:37:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:37:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:37:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:37:44 INFO - '--tppagecycles', 03:37:44 INFO - '7'], 03:37:44 INFO - 'tests': ['tsvgm']}}, 03:37:44 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:37:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:37:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:37:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['damp', 'tps']}, 03:37:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'tests': ['damp', 'tps']}, 03:37:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['dromaeo_dom']}, 03:37:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:37:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:37:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:37:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:37:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['a11yr', 03:37:44 INFO - 'ts_paint', 03:37:44 INFO - 'tpaint', 03:37:44 INFO - 'sessionrestore', 03:37:44 INFO - 'sessionrestore_no_auto_restore', 03:37:44 INFO - 'tabpaint']}, 03:37:44 INFO - 'other-e10s': {'tests': ['a11yr', 03:37:44 INFO - 'ts_paint', 03:37:44 INFO - 'tpaint', 03:37:44 INFO - 'sessionrestore', 03:37:44 INFO - 'sessionrestore_no_auto_restore', 03:37:44 INFO - 'tabpaint']}, 03:37:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:37:44 INFO - 'ts_paint', 03:37:44 INFO - 'tpaint', 03:37:44 INFO - 'sessionrestore', 03:37:44 INFO - 'sessionrestore_no_auto_restore', 03:37:44 INFO - 'tabpaint']}, 03:37:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:37:44 INFO - 'ts_paint', 03:37:44 INFO - 'tpaint', 03:37:44 INFO - 'sessionrestore', 03:37:44 INFO - 'sessionrestore_no_auto_restore', 03:37:44 INFO - 'tabpaint']}, 03:37:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['a11yr', 03:37:44 INFO - 'ts_paint', 03:37:44 INFO - 'tpaint', 03:37:44 INFO - 'sessionrestore', 03:37:44 INFO - 'sessionrestore_no_auto_restore', 03:37:44 INFO - 'tabpaint']}, 03:37:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['a11yr', 03:37:44 INFO - 'ts_paint', 03:37:44 INFO - 'tpaint', 03:37:44 INFO - 'sessionrestore', 03:37:44 INFO - 'sessionrestore_no_auto_restore', 03:37:44 INFO - 'tabpaint']}, 03:37:44 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:37:44 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:37:44 INFO - 'bloom_basic_ref']}, 03:37:44 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 03:37:44 INFO - 'talos_options': ['--mitmproxy', 03:37:44 INFO - 'mitmproxy-recording-1.mp', 03:37:44 INFO - '--firstNonBlankPaint'], 03:37:44 INFO - 'tests': ['Quantum_1']}, 03:37:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['tsvgx', 03:37:44 INFO - 'tsvgr_opacity', 03:37:44 INFO - 'tart', 03:37:44 INFO - 'tscrollx', 03:37:44 INFO - 'cart', 03:37:44 INFO - 'tsvg_static']}, 03:37:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:37:44 INFO - 'tsvgr_opacity', 03:37:44 INFO - 'tart', 03:37:44 INFO - 'tscrollx', 03:37:44 INFO - 'cart', 03:37:44 INFO - 'tsvg_static']}, 03:37:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'talos_options': ['--disable-e10s'], 03:37:44 INFO - 'tests': ['tp5o']}, 03:37:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:37:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'talos_options': ['--disable-e10s', 03:37:44 INFO - '--xperf_path', 03:37:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:44 INFO - 'tests': ['tp5n']}, 03:37:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:37:44 INFO - 'talos_options': ['--xperf_path', 03:37:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:44 INFO - 'tests': ['tp5n']}}, 03:37:44 INFO - 'talos.zip': {'path': '', 03:37:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:37:44 INFO - Running post-action listener: _resource_record_post_action 03:37:44 INFO - [mozharness: 2017-05-31 10:37:44.994840Z] Finished populate-webroot step (success) 03:37:44 INFO - [mozharness: 2017-05-31 10:37:44.994961Z] Running create-virtualenv step. 03:37:44 INFO - Running pre-action listener: _resource_record_pre_action 03:37:44 INFO - Running main action method: create_virtualenv 03:37:44 INFO - Creating virtualenv /builds/slave/test/build/venv 03:37:44 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 03:37:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:37:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:37:45 INFO - Using real prefix '/tools/python27' 03:37:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:37:45 INFO - Installing distribute.............................................................................................................................................................................................done. 03:37:48 INFO - Installing pip.................done. 03:37:48 INFO - Return code: 0 03:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:37:49 INFO - Reading from file tmpfile_stdout 03:37:49 INFO - Output received: 03:37:49 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) 03:37:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:37:49 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')]} 03:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:49 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')]} 03:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:49 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:37:49 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 03:37:49 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 03:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:49 INFO - 'HOME': '/Users/cltbld', 03:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:49 INFO - 'LOGNAME': 'cltbld', 03:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:49 INFO - 'MOZ_NO_REMOTE': '1', 03:37:49 INFO - 'NO_EM_RESTART': '1', 03:37:49 INFO - 'PAGER': '/bin/cat', 03:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:49 INFO - 'PWD': '/builds/slave/test', 03:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:49 INFO - 'SHELL': '/bin/bash', 03:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:37:49 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:49 INFO - 'USER': 'cltbld', 03:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:49 INFO - 'XPC_FLAGS': '0x0', 03:37:49 INFO - 'XPC_SERVICE_NAME': '0', 03:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:49 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 03:37:49 INFO - Cleaning up... 03:37:49 INFO - Return code: 0 03:37:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:37:49 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')]} 03:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:49 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')]} 03:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:49 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:37:49 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 03:37:49 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 03:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:49 INFO - 'HOME': '/Users/cltbld', 03:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:49 INFO - 'LOGNAME': 'cltbld', 03:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:49 INFO - 'MOZ_NO_REMOTE': '1', 03:37:49 INFO - 'NO_EM_RESTART': '1', 03:37:49 INFO - 'PAGER': '/bin/cat', 03:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:49 INFO - 'PWD': '/builds/slave/test', 03:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:49 INFO - 'SHELL': '/bin/bash', 03:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:37:49 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:49 INFO - 'USER': 'cltbld', 03:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:49 INFO - 'XPC_FLAGS': '0x0', 03:37:49 INFO - 'XPC_SERVICE_NAME': '0', 03:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:49 INFO - Downloading/unpacking psutil>=3.1.1 03:37:49 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 03:37:49 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 03:37:49 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 03:37:49 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 03:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:37:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:51 INFO - Installing collected packages: psutil 03:37:51 INFO - Running setup.py install for psutil 03:37:51 INFO - building 'psutil._psutil_osx' extension 03:37:52 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 03:37:52 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 03:37:52 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 03:37:52 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 03:37:52 INFO - building 'psutil._psutil_posix' extension 03:37:52 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 03:37:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:37:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:37:52 INFO - ^ 03:37:52 INFO - 1 warning generated. 03:37:52 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 03:37:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:53 INFO - Successfully installed psutil 03:37:53 INFO - Cleaning up... 03:37:53 INFO - Return code: 0 03:37:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:37:53 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')]} 03:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:53 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')]} 03:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:53 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:37:53 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 03:37:53 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 03:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:53 INFO - 'HOME': '/Users/cltbld', 03:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:53 INFO - 'LOGNAME': 'cltbld', 03:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:53 INFO - 'MOZ_NO_REMOTE': '1', 03:37:53 INFO - 'NO_EM_RESTART': '1', 03:37:53 INFO - 'PAGER': '/bin/cat', 03:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:53 INFO - 'PWD': '/builds/slave/test', 03:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:53 INFO - 'SHELL': '/bin/bash', 03:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:37:53 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:53 INFO - 'USER': 'cltbld', 03:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:53 INFO - 'XPC_FLAGS': '0x0', 03:37:53 INFO - 'XPC_SERVICE_NAME': '0', 03:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:37:53 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 03:37:53 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 03:37:53 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 03:37:53 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 03:37:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:37:54 INFO - Installing collected packages: mozsystemmonitor 03:37:54 INFO - Running setup.py install for mozsystemmonitor 03:37:54 INFO - Successfully installed mozsystemmonitor 03:37:54 INFO - Cleaning up... 03:37:55 INFO - Return code: 0 03:37:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:37:55 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')]} 03:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:55 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')]} 03:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:55 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:37:55 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 03:37:55 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 03:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:55 INFO - 'HOME': '/Users/cltbld', 03:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:55 INFO - 'LOGNAME': 'cltbld', 03:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:55 INFO - 'MOZ_NO_REMOTE': '1', 03:37:55 INFO - 'NO_EM_RESTART': '1', 03:37:55 INFO - 'PAGER': '/bin/cat', 03:37:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:55 INFO - 'PWD': '/builds/slave/test', 03:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:55 INFO - 'SHELL': '/bin/bash', 03:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:37:55 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:55 INFO - 'USER': 'cltbld', 03:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:55 INFO - 'XPC_FLAGS': '0x0', 03:37:55 INFO - 'XPC_SERVICE_NAME': '0', 03:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:37:55 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 03:37:55 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 03:37:55 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 03:37:55 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 03:37:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:37:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:37:57 INFO - warning: no files found matching '*.txt' 03:37:57 INFO - no previously-included directories found matching 'build' 03:37:57 INFO - no previously-included directories found matching 'dist' 03:37:57 INFO - no previously-included directories found matching '.git*' 03:37:57 INFO - Installing collected packages: jsonschema, functools32 03:37:57 INFO - Running setup.py install for functools32 03:37:57 INFO - warning: no files found matching '*.txt' 03:37:57 INFO - no previously-included directories found matching 'build' 03:37:57 INFO - no previously-included directories found matching 'dist' 03:37:57 INFO - no previously-included directories found matching '.git*' 03:37:57 INFO - Successfully installed jsonschema functools32 03:37:57 INFO - Cleaning up... 03:37:57 INFO - Return code: 0 03:37:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37: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')]} 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37: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', '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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:37: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:37: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 functools32==3.2.3-2 03:37:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:37:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:57 INFO - 'HOME': '/Users/cltbld', 03:37:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:57 INFO - 'LOGNAME': 'cltbld', 03:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:57 INFO - 'MOZ_NO_REMOTE': '1', 03:37:57 INFO - 'NO_EM_RESTART': '1', 03:37:57 INFO - 'PAGER': '/bin/cat', 03:37:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:57 INFO - 'PWD': '/builds/slave/test', 03:37:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:57 INFO - 'SHELL': '/bin/bash', 03:37:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:37:57 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:37:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:57 INFO - 'USER': 'cltbld', 03:37:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:57 INFO - 'XPC_FLAGS': '0x0', 03:37:57 INFO - 'XPC_SERVICE_NAME': '0', 03:37:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:37:57 INFO - Cleaning up... 03:37:57 INFO - Return code: 0 03:37:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37: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')]} 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37: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', '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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:37: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 blobuploader==1.2.4 03:37:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:37:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:57 INFO - 'HOME': '/Users/cltbld', 03:37:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:57 INFO - 'LOGNAME': 'cltbld', 03:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:57 INFO - 'MOZ_NO_REMOTE': '1', 03:37:57 INFO - 'NO_EM_RESTART': '1', 03:37:57 INFO - 'PAGER': '/bin/cat', 03:37:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:57 INFO - 'PWD': '/builds/slave/test', 03:37:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:57 INFO - 'SHELL': '/bin/bash', 03:37:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:37:57 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:37:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:57 INFO - 'USER': 'cltbld', 03:37:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:57 INFO - 'XPC_FLAGS': '0x0', 03:37:57 INFO - 'XPC_SERVICE_NAME': '0', 03:37:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:57 INFO - Downloading/unpacking blobuploader==1.2.4 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:37:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:37:59 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 03:37:59 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 03:37:59 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 03:37:59 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 03:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:37:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:37:59 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 03:37:59 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 03:37:59 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 03:37:59 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 03:38:00 INFO - Downloading docopt-0.6.1.tar.gz 03:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:38:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:38:00 INFO - Running setup.py install for blobuploader 03:38:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:38:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:38:00 INFO - Running setup.py install for requests 03:38:00 INFO - Running setup.py install for docopt 03:38:01 INFO - Successfully installed blobuploader requests docopt 03:38:01 INFO - Cleaning up... 03:38:01 INFO - Return code: 0 03:38:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:01 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:38:01 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 03:38:01 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 03:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:01 INFO - 'HOME': '/Users/cltbld', 03:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:01 INFO - 'LOGNAME': 'cltbld', 03:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:01 INFO - 'MOZ_NO_REMOTE': '1', 03:38:01 INFO - 'NO_EM_RESTART': '1', 03:38:01 INFO - 'PAGER': '/bin/cat', 03:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:01 INFO - 'PWD': '/builds/slave/test', 03:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:01 INFO - 'SHELL': '/bin/bash', 03:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:38:01 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:01 INFO - 'USER': 'cltbld', 03:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:01 INFO - 'XPC_FLAGS': '0x0', 03:38:01 INFO - 'XPC_SERVICE_NAME': '0', 03:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-giXJaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-hxJRYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-9mE93V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-leEAG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jzHbFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-pFdm3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:01 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-d8WjTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-iCITp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-XFE4za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-OBNKva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Im1ZgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jKR0O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-y0ZMCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jS9vgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-6LrwnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:02 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-WIpV8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-OIcl6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:38:03 INFO - Running setup.py install for manifestparser 03:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:38:03 INFO - Running setup.py install for mozcrash 03:38:03 INFO - Running setup.py install for mozdebug 03:38:03 INFO - Running setup.py install for mozdevice 03:38:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:38:03 INFO - Running setup.py install for mozfile 03:38:03 INFO - Running setup.py install for mozhttpd 03:38:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:38:03 INFO - Running setup.py install for mozinfo 03:38:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozInstall 03:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozleak 03:38:04 INFO - Running setup.py install for mozlog 03:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for moznetwork 03:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozprocess 03:38:04 INFO - Running setup.py install for mozprofile 03:38:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Running setup.py install for mozrunner 03:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Running setup.py install for mozscreenshot 03:38:05 INFO - Running setup.py install for moztest 03:38:05 INFO - Running setup.py install for mozversion 03:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:38:05 INFO - Cleaning up... 03:38:05 INFO - Return code: 0 03:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:05 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')]} 03:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:05 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')]} 03:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:05 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:38:05 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 03:38:05 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 03:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:05 INFO - 'HOME': '/Users/cltbld', 03:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:05 INFO - 'LOGNAME': 'cltbld', 03:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:05 INFO - 'MOZ_NO_REMOTE': '1', 03:38:05 INFO - 'NO_EM_RESTART': '1', 03:38:05 INFO - 'PAGER': '/bin/cat', 03:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:05 INFO - 'PWD': '/builds/slave/test', 03:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:05 INFO - 'SHELL': '/bin/bash', 03:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:38:05 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:05 INFO - 'USER': 'cltbld', 03:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:05 INFO - 'XPC_FLAGS': '0x0', 03:38:05 INFO - 'XPC_SERVICE_NAME': '0', 03:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:05 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-yt8CQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-aiHnrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-HEID_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-n5hVgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-1EbQ4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-gWshYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Ywg2dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-JhC6Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-7JWPt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-M_6jJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:06 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)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:06 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-TNVNoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:07 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)) 03:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-czgqGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:07 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)) 03:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-qniMuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:07 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)) 03:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-bwNRSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:07 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)) 03:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-y8St7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:07 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)) 03:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-8M_f7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:07 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)) 03:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-nGu_1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:07 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)) 03:38:07 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)) 03:38:07 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)) 03:38:07 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)) 03:38:07 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)) 03:38:07 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)) 03:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:09 INFO - Downloading blessings-1.6.tar.gz 03:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:38:09 INFO - Installing collected packages: blessings 03:38:09 INFO - Running setup.py install for blessings 03:38:09 INFO - Successfully installed blessings 03:38:09 INFO - Cleaning up... 03:38:09 INFO - Return code: 0 03:38:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:09 INFO - Reading from file tmpfile_stdout 03:38:09 INFO - Current package versions: 03:38:09 INFO - blessings == 1.6 03:38:09 INFO - blobuploader == 1.2.4 03:38:09 INFO - docopt == 0.6.1 03:38:09 INFO - functools32 == 3.2.3-2 03:38:09 INFO - jsonschema == 2.5.1 03:38:09 INFO - manifestparser == 1.1 03:38:09 INFO - mozInstall == 1.12 03:38:09 INFO - mozcrash == 1.0 03:38:09 INFO - mozdebug == 0.1 03:38:09 INFO - mozdevice == 0.51 03:38:09 INFO - mozfile == 1.2 03:38:09 INFO - mozhttpd == 0.7 03:38:09 INFO - mozinfo == 0.9 03:38:09 INFO - mozleak == 0.1 03:38:09 INFO - mozlog == 3.4 03:38:09 INFO - moznetwork == 0.27 03:38:09 INFO - mozprocess == 0.25 03:38:09 INFO - mozprofile == 0.28 03:38:09 INFO - mozrunner == 6.13 03:38:09 INFO - mozscreenshot == 0.1 03:38:09 INFO - mozsystemmonitor == 0.3 03:38:09 INFO - moztest == 0.8 03:38:09 INFO - mozversion == 1.4 03:38:09 INFO - psutil == 3.1.1 03:38:09 INFO - requests == 1.2.3 03:38:09 INFO - wsgiref == 0.1.2 03:38:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38: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')]} 03:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38: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')]} 03:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:09 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:38:09 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 03:38:09 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 03:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:09 INFO - 'HOME': '/Users/cltbld', 03:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:09 INFO - 'LOGNAME': 'cltbld', 03:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:09 INFO - 'MOZ_NO_REMOTE': '1', 03:38:09 INFO - 'NO_EM_RESTART': '1', 03:38:09 INFO - 'PAGER': '/bin/cat', 03:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:09 INFO - 'PWD': '/builds/slave/test', 03:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:09 INFO - 'SHELL': '/bin/bash', 03:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:38:09 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:09 INFO - 'USER': 'cltbld', 03:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:09 INFO - 'XPC_FLAGS': '0x0', 03:38:09 INFO - 'XPC_SERVICE_NAME': '0', 03:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 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)) 03:38:10 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:11 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:38:11 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)) 03:38:11 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)) 03:38:11 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)) 03:38:11 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)) 03:38:11 INFO - Installing collected packages: marionette-driver 03:38:11 INFO - Running setup.py install for marionette-driver 03:38:11 INFO - Successfully installed marionette-driver 03:38:11 INFO - Cleaning up... 03:38:11 INFO - Return code: 0 03:38:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:38:11 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')]} 03:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:11 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')]} 03:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:11 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 0x10bf58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9d2d5de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, '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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:38:11 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 03:38:11 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 03:38:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:38:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:11 INFO - 'HOME': '/Users/cltbld', 03:38:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:11 INFO - 'LOGNAME': 'cltbld', 03:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:11 INFO - 'MOZ_NO_REMOTE': '1', 03:38:11 INFO - 'NO_EM_RESTART': '1', 03:38:11 INFO - 'PAGER': '/bin/cat', 03:38:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:11 INFO - 'PWD': '/builds/slave/test', 03:38:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:11 INFO - 'SHELL': '/bin/bash', 03:38:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:38:11 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:38:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:11 INFO - 'USER': 'cltbld', 03:38:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:11 INFO - 'XPC_FLAGS': '0x0', 03:38:11 INFO - 'XPC_SERVICE_NAME': '0', 03:38:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:38:12 INFO - Cleaning up... 03:38:12 INFO - Return code: 0 03:38:12 INFO - Running post-action listener: _resource_record_post_action 03:38:12 INFO - Running post-action listener: _start_resource_monitoring 03:38:12 INFO - Starting resource monitoring. 03:38:12 INFO - [mozharness: 2017-05-31 10:38:12.072645Z] Finished create-virtualenv step (success) 03:38:12 INFO - [mozharness: 2017-05-31 10:38:12.073630Z] Running install step. 03:38:12 INFO - Running pre-action listener: _resource_record_pre_action 03:38:12 INFO - Running main action method: install 03:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:12 INFO - Reading from file tmpfile_stdout 03:38:12 INFO - Detecting whether we're running mozinstall >=1.0... 03:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:38:12 INFO - Reading from file tmpfile_stdout 03:38:12 INFO - Output received: 03:38:12 INFO - Usage: mozinstall [options] installer 03:38:12 INFO - Options: 03:38:12 INFO - -h, --help show this help message and exit 03:38:12 INFO - -d DEST, --destination=DEST 03:38:12 INFO - Directory to install application into. [default: 03:38:12 INFO - "/builds/slave/test"] 03:38:12 INFO - --app=APP Application being installed. [default: firefox] 03:38:12 INFO - mkdir: /builds/slave/test/build/application 03:38:12 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'] 03:38:12 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 03:38:34 INFO - Reading from file tmpfile_stdout 03:38:34 INFO - Output received: 03:38:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:38:34 INFO - Running post-action listener: _resource_record_post_action 03:38:34 INFO - [mozharness: 2017-05-31 10:38:34.330623Z] Finished install step (success) 03:38:34 INFO - [mozharness: 2017-05-31 10:38:34.330751Z] Running setup-mitmproxy step. 03:38:34 INFO - Running pre-action listener: _resource_record_pre_action 03:38:34 INFO - Running main action method: setup_mitmproxy 03:38:34 INFO - Skipping: mitmproxy is not required 03:38:34 INFO - Running post-action listener: _resource_record_post_action 03:38:34 INFO - [mozharness: 2017-05-31 10:38:34.331435Z] Finished setup-mitmproxy step (success) 03:38:34 INFO - [mozharness: 2017-05-31 10:38:34.331580Z] Running run-tests step. 03:38:34 INFO - Running pre-action listener: _resource_record_pre_action 03:38:34 INFO - Running main action method: run_tests 03:38:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:38:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:38:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:38:34 INFO - 2017-05-31 03:38:34.346 screenresolution[1654:8154] starting screenresolution argv=screenresolution get 03:38:34 INFO - 2017-05-31 03:38:34.348 screenresolution[1654:8154] Display 0: 1600x1200x32@60 03:38:34 INFO - 2017-05-31 03:38:34.354 screenresolution[1655:8156] starting screenresolution argv=screenresolution list 03:38:34 INFO - Available Modes on Display 0 03:38:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:38:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:38:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:38:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:38:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:38:34 INFO - Intel Iris: 03:38:34 INFO - Chipset Model: Intel Iris 03:38:34 INFO - Type: GPU 03:38:34 INFO - Bus: Built-In 03:38:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:38:34 INFO - Vendor: Intel (0x8086) 03:38:34 INFO - Device ID: 0x0a2e 03:38:34 INFO - Revision ID: 0x0009 03:38:34 INFO - Displays: 03:38:34 INFO - Display: 03:38:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:38:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:38:34 INFO - Display Serial Number: 1600x1200 60 03:38:34 INFO - Main Display: Yes 03:38:34 INFO - Mirror: Off 03:38:34 INFO - Online: Yes 03:38:34 INFO - Rotation: Supported 03:38:34 INFO - Return code: 0 03:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:38:34 INFO - Python 2.7.3 03:38:34 INFO - Return code: 0 03:38:34 INFO - grabbing minidump binary from tooltool 03:38:34 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')]} 03:38:34 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 0x10bee1c60>, '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 0x10bc76cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc9fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:38:34 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 03:38:34 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 03:38:34 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 03:38:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:38:34 INFO - Return code: 0 03:38:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:38:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:38:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:38:34 INFO - ENV: RUST_BACKTRACE is now 1 03:38:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:38:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:38:34 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/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 03:38:34 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/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0224 --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 03:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hC5ZB2eYg4/Render', 03:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:34 INFO - 'HOME': '/Users/cltbld', 03:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:34 INFO - 'LOGNAME': 'cltbld', 03:38:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:34 INFO - 'MOZ_NO_REMOTE': '1', 03:38:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:34 INFO - 'NO_EM_RESTART': '1', 03:38:34 INFO - 'PAGER': '/bin/cat', 03:38:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:34 INFO - 'PWD': '/builds/slave/test', 03:38:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:34 INFO - 'RUST_BACKTRACE': '1', 03:38:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:34 INFO - 'SHELL': '/bin/bash', 03:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJeEA9KjaY/Listeners', 03:38:34 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:34 INFO - 'USER': 'cltbld', 03:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:34 INFO - 'XPC_FLAGS': '0x0', 03:38:34 INFO - 'XPC_SERVICE_NAME': '0', 03:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:34 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/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 03:38:34 INFO - mozversion application_buildid: 20170531022434 03:38:34 INFO - mozversion application_changeset: 06d83f260f410f125847e95618b6f8b9e83e9a2f 03:38:34 INFO - mozversion application_display_name: Nightly 03:38:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:38:34 INFO - mozversion application_name: Firefox 03:38:34 INFO - mozversion application_remotingname: firefox 03:38:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:38:34 INFO - mozversion application_vendor: Mozilla 03:38:34 INFO - mozversion application_version: 55.0a1 03:38:34 INFO - mozversion platform_buildid: 20170531022434 03:38:34 INFO - mozversion platform_changeset: 06d83f260f410f125847e95618b6f8b9e83e9a2f 03:38:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:38:34 INFO - mozversion platform_version: 55.0a1 03:38:34 INFO - using testdate: 1496227114 03:38:34 INFO - actual date: 1496227114 03:38:34 INFO - starting webserver on 'localhost:49230' 03:38:34 INFO - SUITE-START | Running 2 tests 03:38:34 INFO - TEST-START | dromaeo_css 03:38:34 INFO - operating with platform_type : mac_ 03:38:34 INFO - Initialising browser for dromaeo_css test... 03:38:35 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpcsLaJV/profile 03:38:35 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 03:39:08 INFO - TEST-INFO | 1665: killed by SIGTERM 03:39:08 INFO - Browser initialized. 03:39:08 INFO - Running cycle 1/1 for dromaeo_css test... 03:39:08 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpcsLaJV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:39:10 INFO - PID 1673 | 2017-05-31 03:39:10.851 plugin-container[1674:8643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 03:39:10 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:39:10 INFO - PID 1673 | 2017-05-31 03:39:10.852 plugin-container[1674:8643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 03:39:10 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:39:10 INFO - PID 1673 | 2017-05-31 03:39:10.852 plugin-container[1674:8643] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:39:11 INFO - PID 1673 | 2017-05-31 03:39:11.542 plugin-container[1675:8690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 03:39:11 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:39:11 INFO - PID 1673 | 2017-05-31 03:39:11.542 plugin-container[1675:8690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 03:39:11 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:39:11 INFO - PID 1673 | 2017-05-31 03:39:11.542 plugin-container[1675:8690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:39:20 INFO - PID 1673 | RSS: Main: 260558848 03:39:20 INFO - PID 1673 | 03:42:23 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 03:42:24 INFO - PID 1673 | RSS: Main: 274583552 03:42:24 INFO - PID 1673 | 03:45:26 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 03:45:27 INFO - PID 1673 | RSS: Main: 288980992 03:45:27 INFO - PID 1673 | 03:48:28 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 03:48:29 INFO - PID 1673 | RSS: Main: 288174080 03:48:29 INFO - PID 1673 | 03:51:31 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 03:51:31 INFO - PID 1673 | RSS: Main: 291921920 03:51:31 INFO - PID 1673 | 03:54:34 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 03:54:35 INFO - PID 1673 | RSS: Main: 292417536 03:54:35 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 03:57:37 INFO - PID 1673 | RSS: Main: 294010880 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | __start_tp_report 03:57:37 INFO - PID 1673 | _x_x_mozilla_page_load 03:57:37 INFO - PID 1673 | _x_x_mozilla_page_load_details 03:57:37 INFO - PID 1673 | |i|pagename|runs| 03:57:37 INFO - PID 1673 | |0;dojo.html;3603.9639603603955;3606.6934310583597;3632.4551317302416;3658.670719635233;3665.9633403665957;6148.047052706826;6148.600340977833;6248.875022499542;6256.655883926388;6276.340984196662;6227.53293502988;6249.468795152417;6250.749970001195;6259.217597800275;6319;5427.375851777037;5521.723913804314;5526.226328314039;5528.5024347808685;5585.553155747551;5581.49766521013;5586.217929489874;5648.519875810561;5654.6041777075625;5716.028275193227;4614.630829533646;5407.891842163135;5559.054960656698;5580.497755202051;5637.492625663689;4554.3851580036935;5468.7265636718;5549.19536667181;5594.049011668027;5597.076482380402;4521.367008618796;5477.287952566181;5484.698341591211;5530.419305972855;5543.805966791143;6258.06129080637;6284.465820405271;6302.401271879165;6308.968455157741;6314.147590075373;6134.263888333384;6617.86764264712;6709.7651582194385;6766.120404347453;6805.625690587065;6068.877257707294;6716.328367163244;6923.723051077951;6930.480213983921;6938.757143500004;6348.111264423031;6354.9364506354805;6355.396237357444;6361.332060133712;6393.936060639334;6775.3902148805855;6866.931330686729;6985.231624521299;7040.823979400556;7055.3650171484815;6427.871442571216;6492.480601551864;6550.246721627074;6611.570247933869;6633.071370008202;6834.179898412222;6851.2121106072445;6868.072810170663;6871.278515755874;6871.484638652121;6641.468682505388;6749.527533072736;6749.797506074826;6767.898481522781;6770.492213084039;6445.097686323918;6538.346165383518;6645.867082658319;6717.764878229238;6793.558418702769;5353.982743278764;5359.839204823861;5364.275822763877;5374.301340825746;5410.3778065522965;4781.713097214179;4786.497417771154;4792.041591681608;4811.398575178103;4818.6626936114135;5610.354809196913;5944.405559444021;6281.905771413432;6315.842103947346;6348.809535713844;6877.862442751117;6900.3444672756;6907.309269073153;6916.723331066701;6944.513884028171;6302;6347.301796802348;6355;6367.586106903036;6425.903611445832;5818.185454036438;5869.06095024794;5959.4934430573885;6005.609635373687;6046.455818976313;7015.403690686247;7032.578045317297;7035.33164349386;7067.293270672892;7073.115860517435;6747.932520674731;6913.723451061901;6915.066466027022;6937.375636192565;6940.201876784235;5643.407442218508;6374.330695276929;6790.490713196629;7025.367716905503;7039.120109986252;6676.232233293039;6707.731690732511;6721.126253587003;6767.695453704693;6798.490113241428;5762.567807414377;6913.3777959983845;6930.688119034757;6937.930620693728;6947.5831450113155;6430.742770289136;6520.021996700533;6575.671216439255;6601;6664.067030615621;6634.933650663625;6677.3656502632175;6680.732770689118;6686.0973768540625;6718.328167183243;6447.903281450688;6557.540972131905;6578.243501997332;6593.736250549924;6608.173978252718;6475;6509.7396104155305;6655.168103987002;6693.029510721016;6719.596824190564;6421.903671445026;6455.2576453708425;6498.3826536478955;6501.707423166064;6525.836854078686 03:57:37 INFO - PID 1673 | |1;ext.html;788.1684822512251;824.2087595907929;849.5667209723043;1258.7482503499298;1329.5080820096566;38367.61632383679;39442.60557394425;39517.01207469815;39549.406758898636;39590.80204598977;39536;39865.20269594606;39914.800425997826;39957.800210998976;40028.39957400641;7864.837169778328;8044.436889417743;8169.223923727236;8380.706675266367;8383.245507904287;8258.83482330353;8407.62165702545;8417.410781245315;8433.662653493853;8448.324134069291;8348.457350272243;8361;8456.78858028551;8461.492310461392;8487.193716596945;8379.83240335193;8411.915880841174;8430.915690843074;8434.325253979667;8436.873446898302;8181.550014749187;8232.83534329316;8239.093699693029;8246.958765206136;8274.958625206895;30887.918922837816;37373.43939840904;37611.43582846259;37613.43579846305;37711.43432848482;3582.5700915889997;3599.5860476045327;3649.434337677638;3652.36083685354;3654.3787556115526;30314.090577282717;30444;30474.085777426713;30476.54285185724;30478.54282185769;3047.405755877605;3101.6433110192165;3111.548825420324;3118.1425108095227;3130.906072817819;236856;237522;239040;296925;298783;39631.20737585232;48676.78308042327;48698.02603947972;48734.75632621814;48840;49105.75447122813;49752.75123624359;50671.493285067416;50871.491285086675;51054.234186487236;14011.43954241819;14147.64630884236;14192.361343739556;14219.146851188962;14232.430702771773;11248.94375528117;11297.830532542019;11311.660650180507;11317.603883864025;11394.886051139549;11898.929096381367;13493.527726529524;14104.871610271153;14111.506097286545;14181.85818141826;5273.44628813969;5674.460926212003;6056;6100.389961003864;6113.174721412642;1009.6920439265947;1036.3988886445843;1044.9007344302279;1064.4411683866033;1075.3655343347461;18979.33572324962;23516.7648323518;23620.763792361857;24789.62815557768;24837.254882353558;1111.1166622535104;1112.9276597021167;1146.140394703972;1148.8563929508812;1162.68607475981;10376.584936602603;11654.184207105422;12549.874501255053;12805.167664101804;12832;268664;331151;332168;332440;332895;54036.45963540393;54091.45908540943;54495.63760906009;54549;54555.6361090968;1479.474786450816;1516.066411493741;1523.8095238095239;1529.3041666041925;1543.614096475881;23969.640455392833;24108.51782964366;24154.154604588755;24236.51526969486;24518.63222051635;38510.80744596259;38604;38636.2272754549;38761.224775504896;38898;280898.19101809536;282938;284123.73814393213;284758.5762071259;285389;34933.47599785955;35914.820425897706;35921.46117808287;36120.81939590285;36160.81919590385;43265;43695.34456983084;45106.097878042914;45207.773961129984;45327.54672453233;28708.421036843174;30395.784168633007;30543;31312;32357.029289120423;28413.573796393484;30411.391772164876;30651.2337191572;30681.53977690381;30718.692813071582;32721.509177361884;34637.48043779396;36094.4585831218;36664;37404.81297593604;30014.54978175373;30401.239969000068;31288.530672039484;31300.686993129777;31444.371112577162;22895.65656515187;22998.540029199656;23308.883455582614;24583.13959011426;24826.50346993014 03:57:37 INFO - PID 1673 | |2;jquery.html;375.91729819439723;393.33133672756315;418.5354256774979;431.8812326610184;455.6150053205043;21596.676049859245;22352.3294301171;22384.77615223847;22442.214522491715;22477.32568022958;24282.878585607046;25214.74785252147;25323.62014569783;25334.746652533468;25353.49293014143;7063.7174513019545;7186.281371862824;7218.350348468637;7222.988781570584;7241.565506069653;7086.255943125975;7150.964245178767;7157.2842715728275;7162.713491460336;7176.964115179417;6844.7262109515805;7079.823004424905;7090.751823686172;7104;7152.034475345814;7052.471064670144;7094.71621135154;7110.893336599956;7124.964375178117;7139.750108746195;6951.548149370275;6964.477664175207;6970;6971;6983.092198014277;28663.710133044064;28763.424731505464;30407.847960760275;30743.69256307453;30835;2238.5858616156065;2272.9204477843195;2277.3281881844896;2282.258266063536;2288.2448791898632;16874.83125168745;19987.300444484517;20352.69470957937;20614.072366743534;20668.79331206684;2281.863088214712;2299.7700229977036;2354.505553833697;2355.4935688826986;2389.629607410854;123499;149589;167391;190909;192631.0368448163;25717.48565028689;25735;27283.181504554894;27780.58329125065;27886.72113278841;34828.30343393118;42888.57111428908;43205.567944320785;43268.56731432708;43334;10799.352038877692;10819.729506762395;10878.673639790819;10899.727506812393;10916;8493.830123397498;8552.957235213784;8576.914230857736;8656.523891186045;8679.392442529088;11623.012043976187;12082.408684699934;12252.754944901051;12276;12377.566785162477;4345.283028300295;4907.901841963141;4925.2119660854805;4926.881440980413;4934.703917764946;888.3692578269372;975.6292608808608;982.1259079419323;1011.7622358745836;1044.5821671331528;21188;21444.463888402603;21724.022418991182;21913.342599722033;21978.780212197675;873.689840106753;926.6849271247808;1011.3780025284398;1055.6094245129352;1057.9365238085738;10447.68656940293;10718.785624287471;10744.656917885264;11008.284461509977;11288.153388495895;554664;556007.4399256037;556292;564725;566586.167069162;155394;158197.62703559455;158906.20546897192;159459;159648;1123.6797512708838;1150.2408410449063;1221.4869754703045;1234.1916044990546;1237.418413345744;21896.452588684773;22229.555408891407;23276;23518.88240558786;23626.52746945086;68417;69135.92592222242;70624;72503;72685;92068;92099.53950230205;92335.07664923533;92426.53786731024;92893.53553232191;9944.552495137881;10232.948835255776;10378.221633377376;10384.636537721144;10385.584576617153;17120.143992800062;18453.354132605295;18512.907435462737;18576.25694972186;18632.906835466278;14885.330160143036;15295.694086118438;15302.234888255765;15448.073115613099;15449.922750386626;13691.452341906212;13949.23279219612;14008.649783755487;14078.084924480285;14093.013489056078;14394.568162955127;15329.846701532842;15531.84468155304;15644.061356318658;15659.451919182773;10312.535935883054;10342.844857327296;10437.791244174921;10458.947705261426;10492.737681558023;11127.054200393057;14015.36930838135;14404.279786010447;14804.963652544218;14964 03:57:37 INFO - PID 1673 | |3;mootools.html;1253.8056601226813;1255.9686007849805;1429.463951018368;1433.412300956608;1438.8057612222347;11506.654800355993;11605.883941160586;11907.76184476311;11917.165798394106;12351.444185011673;11632.476538555764;11959.043276537877;11959.940200299008;11970.700732481693;11982.460789264482;2269.3305474884864;2299.2067736630834;2312.4218945263683;2314.918977835776;2319.512902290521;2465.642481840132;2509.4479214572766;2509.912153074643;2511.635812807142;2515.55977703902;2462.150558057467;2472.196536125757;2485.7389974052735;2486.9378265543323;2499.575072237724;2430.805535557151;2457.7050753909557;2460.1635443949053;2460.3972026853444;2471.765182307678;2418.3107814273026;2425.3815277104227;2437.305367970138;2466.7656572625574;2538.2765911715255;11670.299782013022;11758.823617645741;11771.352575608338;11790.410479476077;11805.055595552336;6357.586756860789;6643.169603799525;6643.534952553323;6645.501587380966;6652.8669426611195;4068.4304197412384;4252.4046633471335;4305.182015417061;4324.178406102862;4348.826046958118;1166.9824952625718;1239.7582471418077;1242.4160644497072;1243.1422318600228;1266.645339304996;14533.70932581353;14765.630859228606;14843.332050057774;16086.115263660495;16108.919455402765;4845.63657725672;5313.681179129265;5327.147656374961;5408.269883565747;5570.693611851387;9956.551955162035;11386.032187264176;11695.824562631567;11701.590444334408;11702.2978621283;6310.1481300024825;6429.32492088324;6434.388733070351;6437.839054023687;6442.226932768098;5983;6016.759329626766;6056.485198758067;6121.816345509642;6125.693715314305;3907.9609203908167;4308.482982042175;4357.694961352675;4410.536893626123;4412.646988240933;1231.2735486063266;1255.5731051442554;1264.9620511384674;1266.265565971734;1270.4664041102758;760.152430040512;768.7040489411617;794.5709316968772;797.2067792546337;797.9920200797918;6064.4845188158615;6676.432503237279;6960.234374218832;6998.265182155902;7015.3023324914975;881.4887365328094;927.7495076329353;930.7393929699695;930.7580029192324;931.4271722890373;3915.47141135943;3923.7841918694176;3971.7219794614675;4369.257226271541;4457.175422546839;13565.59303220905;14980.475683351031;14998.025128366622;15012.699746005019;15041.172735499653;12060.939695301642;14919.328630211667;15189.012714173543;15194.924025379802;15200.46798362052;8351.74723791424;8619.569021548898;8628.094050124897;8692.869606955901;8736.432131911533;6094.878102438015;6869.484788640772;6983.917492788626;7137.10786151731;7150.499465037397;13579.728405431639;13710.794338084737;13829;14051;14069.577912662637;11595.710107247387;13877.375518101508;13880.375383107583;13963.860361396257;14081.577552673436;9260.907390926004;9574.377665451724;9580.233581313618;9588.904110958802;9598.376105553116;9477.289203309918;10633.255672102878;10774.24580279373;10779.67660970172;10782.137429005817;8904.19862212404;9226.261899048195;9244.260459163117;9261.53692315395;9261.953690231505;9006.819863602823;9030.413023153475;9067;9114.99735029133;9132.908670913206;8178.1004089548915;8193.46742461762;8200.917990820015;8281.503109813431;8290.792730181794;4710.740909250024;5980.192673988956;6058.878822423615;6189.257289125334;6212.937870621236;5579.005138951287;6068.241469816273;6138.815835524942;6186.628802271878;6290.874182516415 03:57:37 INFO - PID 1673 | |4;prototype.html;383.8464614154338;397.19766072533486;399.19163693520636;400.15367497742267;408.04313883942143;4912.06670732561;6032.969835150823;6084.756609735611;6202.968985155079;6347.777827776029;5372.086745253307;5893.58744887858;6173.290071641763;6193.876122477548;6212.471939885104;4432.601065904068;4514.322851572258;4560.657950653697;4576.725396476222;4580.541945805426;4530.093981203756;4547.886302842422;4564.383808185902;4576.222042252825;4582.656300777438;3361;4494.842680506183;4496.730196188223;4514.887127821798;4517.944335327435;4516.6838321317355;4520.9095818083615;4537.478190008158;4541.750203738793;4545.977270113662;3204.3911656785135;3305.5372247885316;3810.8567429770956;3889.5138107736534;3895.9026024349337;6050.428567858687;6082.209312789353;6082.817515474543;6095.603785753912;6106.969465152691;5422.779874528239;5426.565874730051;5430;5434.8913021739345;5447.155690867882;5949.732262048218;6272.714093610799;6543.756686229601;6551.410373066399;6672.932330827093;4391.6486681065435;4394.318880573484;4398.966242932908;4399.956000439986;4402.119576084764;5526.977509160778;5794.5074668653215;6500.772472963471;7016.859662806715;7117.217106118323;3696.9445458318146;4281.908113431117;4369.497507786646;4390.956090439118;4400.669949753781;4492.281235002384;4504.851262927998;4509.549045095529;4511;4525.646663734905;5301.787928482895;5340.91988620171;5351.51836334724;5372.516473517402;5465.890682186334;6619.602823830489;6729.192496900306;6738.561993470409;6744.831379215559;6753.797386078426;1747.5281673947964;1762.242235838576;1763.7530745695612;1764.80587135415;1769.6018395860829;1181.7222952606303;1219.841420615332;1226.957056503018;1235.184778046485;1242.6334231401759;6256.405641464054;6280.685965701697;6316;6328.841778955563;6333.46165575922;6304.558680892385;6854.2117656470145;6953.687084081127;6982.546134501241;7039.894401583981;5458.4541545845095;5824.737886795105;6279.591826531261;6307.180066591405;6310.43206111443;3060.4185204811015;3635.654612811779;3636.5272514573467;3646.252518233756;3655.1958569114754;6061.666608336495;6172.037422037447;6761.391474767294;7125.92874071263;7130.108736407949;6532.542722009413;6621.569597976212;7147.963545286008;7158.2483839197175;7165.713371465187;5233.764480598382;6524.510661700391;6752.953292239711;6977.89533157003;7146.749863754743;5745.482906538265;6773;6964.423163429564;6990.61551614666;6992.650367481708;5880.676562789087;6318.652474113968;6815.42068924147;6837.384635382839;6849.452043836382;6730.831729206809;7073.752418665322;7106.964465177641;7127.643617819192;7129.429645628234;4960.355153829979;4999.600031997505;5003;5003.949170674054;5008.574271186844;5605.803796867253;6379.936200637934;6921.584704917721;7005.649717514206;7042.471814613822;6659.733610655519;6683.8997415039785;6714.261431242657;6715.731370745116;6808;6709.161354830646;6732.192136943598;6733.764318248838;6741.258461569321;6792.660366981532;6554.508411869224;6572.572782769104;6581.276059633532;6587.176602924635;6593.274739778716;6241.344658810759;6446.194225721785;6703.832404189934;6725.798226053226;6756.358145976124;6498.902516462352;6519.035082899284;6531.673416329259;6536.673166341759;6561.212654481493 03:57:37 INFO - PID 1673 | |5;yui.html;742.9591372474515;808.4623725222727;827.1439060572312;833.5332213960181;853.0019876744207;16911.492655220347;17403.12984350784;17419.129043547837;17446.214920328584;17450.738238926428;14061.367238474268;15478.993865398741;15820.841791582081;16538.173091345445;16664.83335166648;945.7021038372891;993.3394292795282;995.9751006224866;997.3118548201754;999.535216124502;999.6950929966357;1003.0922015575916;1004.1364426593124;1004.7287232447235;1016.8627235323211;985.6845809340978;1035.5961175141701;1037.4294138223984;1038.4288641247322;1038.9636362727306;991.8065977134462;1016.0246163682799;1029.8300780371267;1030.0369154840212;1030.4178139351243;747.1706405889458;754.890540871576;757.7764559454974;758.135725273189;759.2104211619909;16878.578035549086;17148.828511714848;17375.30498780047;17377.218025188897;17560.736588951175;17682.911585442118;17826.286948522044;17874.106294685214;18198.63602727938;18235.361762338256;12900.612981610566;14618.342174602169;14730.263486825615;14913.328900199516;14937.551873443705;3561.1631266652316;3572.7499075064757;3593.389123848952;3597.460380942853;3818.7899665518385;139699;142355;142690;142978;143874;13413.262270575016;13628.727425451634;13746.65633359174;13851.030427869953;13868.722625547432;26443.867780660974;26675.46649067008;26905.461890762468;27203.183904482896;27268.181954541396;7345.118585769635;7591.278828511283;7810.804729881798;8003.319717823933;8007.718764997572;3426.554547908806;3932.528096628366;4002.8198731057173;4024.959750402517;4040.818163182605;4325.7620830853975;4378.671599630041;4413.845515406945;4432.822687092545;4459.7547134907245;849.0999540487103;858.6176256227469;866.7009881590841;870.9259712924472;871.6339137562239;578.2598274208935;611.9877602447926;653.2291895563282;658.2857599504496;692.8302565871394;9697.9030209697;10130.493475326204;12234.204776689487;12449.377531123408;12501.312427816558;654.8559316950359;655.6295692933477;663.970121344531;669.1167658690482;679.2629996453903;5469.589780766459;5601.29983752031;5817.650940943557;5881.617694849821;5896.204012458349;152521.71217431748;154889;156162;160451;160671;130848;134560.32719836337;134749.32625337006;135530;135970.3201484006;115916.42041789906;116080;117036;117336;117744;11101.111911046935;11604.419779010846;11637.127215458706;11667.708307292385;11717.472713728073;39301.41047884227;40935;47091.76454117707;47451;48090;62791;63004.36995629985;63265.68367158135;63330.68334658297;64345;14300.499482518062;14310.427582896567;14623.707525849637;14736.705265894409;14760.450152734238;13412.664683382993;15671.529854104396;15985.600359991093;16086;16288.918555407146;14992.850071499146;15052;15138.016028958082;15264.847351526343;15280.61798455003;14521.636959075686;14662.120272783666;15067.397304108152;15131.167785771448;15200.087994720352;14370.568882933529;14447.422103115758;14525.92737036308;14650.853491464948;14746.483873064391;8792.912070879465;10855.077318428022;10897.945510272715;11018.173636977099;11041.779164416826;11099;11435.542578296774;11898.405079745806;12039.699007524532;12176.45205965716 03:57:37 INFO - PID 1673 | __end_tp_report 03:57:37 INFO - PID 1673 | __start_cc_report 03:57:37 INFO - PID 1673 | _x_x_mozilla_cycle_collect,451 03:57:37 INFO - PID 1673 | __end_cc_report 03:57:37 INFO - PID 1673 | __startTimestamp1496228257286__endTimestamp 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | ------- Summary: start ------- 03:57:37 INFO - PID 1673 | Number of tests: 6 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | [#0] dojo.html Cycles:165 Average:6220.85 Median:6430.74 stddev:756.34 (11.8%) stddev-sans-first:730.27 03:57:37 INFO - PID 1673 | Values: 3604.0 3606.7 3632.5 3658.7 3666.0 6148.0 6148.6 6248.9 6256.7 6276.3 6227.5 6249.5 6250.7 6259.2 6319.0 5427.4 5521.7 5526.2 5528.5 5585.6 5581.5 5586.2 5648.5 5654.6 5716.0 4614.6 5407.9 5559.1 5580.5 5637.5 4554.4 5468.7 5549.2 5594.0 5597.1 4521.4 5477.3 5484.7 5530.4 5543.8 6258.1 6284.5 6302.4 6309.0 6314.1 6134.3 6617.9 6709.8 6766.1 6805.6 6068.9 6716.3 6923.7 6930.5 6938.8 6348.1 6354.9 6355.4 6361.3 6393.9 6775.4 6866.9 6985.2 7040.8 7055.4 6427.9 6492.5 6550.2 6611.6 6633.1 6834.2 6851.2 6868.1 6871.3 6871.5 6641.5 6749.5 6749.8 6767.9 6770.5 6445.1 6538.3 6645.9 6717.8 6793.6 5354.0 5359.8 5364.3 5374.3 5410.4 4781.7 4786.5 4792.0 4811.4 4818.7 5610.4 5944.4 6281.9 6315.8 6348.8 6877.9 6900.3 6907.3 6916.7 6944.5 6302.0 6347.3 6355.0 6367.6 6425.9 5818.2 5869.1 5959.5 6005.6 6046.5 7015.4 7032.6 7035.3 7067.3 7073.1 6747.9 6913.7 6915.1 6937.4 6940.2 5643.4 6374.3 6790.5 7025.4 7039.1 6676.2 6707.7 6721.1 6767.7 6798.5 5762.6 6913.4 6930.7 6937.9 6947.6 6430.7 6520.0 6575.7 6601.0 6664.1 6634.9 6677.4 6680.7 6686.1 6718.3 6447.9 6557.5 6578.2 6593.7 6608.2 6475.0 6509.7 6655.2 6693.0 6719.6 6421.9 6455.3 6498.4 6501.7 6525.8 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | [#1] ext.html Cycles:180 Average:44155.77 Median:331659.50 stddev:76048.14 (22.9%) stddev-sans-first:76191.77 03:57:37 INFO - PID 1673 | Values: 788.2 824.2 849.6 1258.7 1329.5 38367.6 39442.6 39517.0 39549.4 39590.8 39536.0 39865.2 39914.8 39957.8 40028.4 7864.8 8044.4 8169.2 8380.7 8383.2 8258.8 8407.6 8417.4 8433.7 8448.3 8348.5 8361.0 8456.8 8461.5 8487.2 8379.8 8411.9 8430.9 8434.3 8436.9 8181.6 8232.8 8239.1 8247.0 8275.0 30887.9 37373.4 37611.4 37613.4 37711.4 3582.6 3599.6 3649.4 3652.4 3654.4 30314.1 30444.0 30474.1 30476.5 30478.5 3047.4 3101.6 3111.5 3118.1 3130.9 236856.0 237522.0 239040.0 296925.0 298783.0 39631.2 48676.8 48698.0 48734.8 48840.0 49105.8 49752.8 50671.5 50871.5 51054.2 14011.4 14147.6 14192.4 14219.1 14232.4 11248.9 11297.8 11311.7 11317.6 11394.9 11898.9 13493.5 14104.9 14111.5 14181.9 5273.4 5674.5 6056.0 6100.4 6113.2 1009.7 1036.4 1044.9 1064.4 1075.4 18979.3 23516.8 23620.8 24789.6 24837.3 1111.1 1112.9 1146.1 1148.9 1162.7 10376.6 11654.2 12549.9 12805.2 12832.0 268664.0 331151.0 332168.0 332440.0 332895.0 54036.5 54091.5 54495.6 54549.0 54555.6 1479.5 1516.1 1523.8 1529.3 1543.6 23969.6 24108.5 24154.2 24236.5 24518.6 38510.8 38604.0 38636.2 38761.2 38898.0 280898.2 282938.0 284123.7 284758.6 285389.0 34933.5 35914.8 35921.5 36120.8 36160.8 43265.0 43695.3 45106.1 45207.8 45327.5 28708.4 30395.8 30543.0 31312.0 32357.0 28413.6 30411.4 30651.2 30681.5 30718.7 32721.5 34637.5 36094.5 36664.0 37404.8 30014.5 30401.2 31288.5 31300.7 31444.4 22895.7 22998.5 23308.9 24583.1 24826.5 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | [#2] jquery.html Cycles:180 Average:40108.73 Median:2285.25 stddev:95976.74 (4199.8%) stddev-sans-first:96199.62 03:57:37 INFO - PID 1673 | Values: 375.9 393.3 418.5 431.9 455.6 21596.7 22352.3 22384.8 22442.2 22477.3 24282.9 25214.7 25323.6 25334.7 25353.5 7063.7 7186.3 7218.4 7223.0 7241.6 7086.3 7151.0 7157.3 7162.7 7177.0 6844.7 7079.8 7090.8 7104.0 7152.0 7052.5 7094.7 7110.9 7125.0 7139.8 6951.5 6964.5 6970.0 6971.0 6983.1 28663.7 28763.4 30407.8 30743.7 30835.0 2238.6 2272.9 2277.3 2282.3 2288.2 16874.8 19987.3 20352.7 20614.1 20668.8 2281.9 2299.8 2354.5 2355.5 2389.6 123499.0 149589.0 167391.0 190909.0 192631.0 25717.5 25735.0 27283.2 27780.6 27886.7 34828.3 42888.6 43205.6 43268.6 43334.0 10799.4 10819.7 10878.7 10899.7 10916.0 8493.8 8553.0 8576.9 8656.5 8679.4 11623.0 12082.4 12252.8 12276.0 12377.6 4345.3 4907.9 4925.2 4926.9 4934.7 888.4 975.6 982.1 1011.8 1044.6 21188.0 21444.5 21724.0 21913.3 21978.8 873.7 926.7 1011.4 1055.6 1057.9 10447.7 10718.8 10744.7 11008.3 11288.2 554664.0 556007.4 556292.0 564725.0 566586.2 155394.0 158197.6 158906.2 159459.0 159648.0 1123.7 1150.2 1221.5 1234.2 1237.4 21896.5 22229.6 23276.0 23518.9 23626.5 68417.0 69135.9 70624.0 72503.0 72685.0 92068.0 92099.5 92335.1 92426.5 92893.5 9944.6 10232.9 10378.2 10384.6 10385.6 17120.1 18453.4 18512.9 18576.3 18632.9 14885.3 15295.7 15302.2 15448.1 15449.9 13691.5 13949.2 14008.6 14078.1 14093.0 14394.6 15329.8 15531.8 15644.1 15659.5 10312.5 10342.8 10437.8 10458.9 10492.7 11127.1 14015.4 14404.3 14805.0 14964.0 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | [#3] mootools.html Cycles:180 Average:7054.95 Median:3947.75 stddev:4440.53 (112.5%) stddev-sans-first:4431.58 03:57:37 INFO - PID 1673 | Values: 1253.8 1256.0 1429.5 1433.4 1438.8 11506.7 11605.9 11907.8 11917.2 12351.4 11632.5 11959.0 11959.9 11970.7 11982.5 2269.3 2299.2 2312.4 2314.9 2319.5 2465.6 2509.4 2509.9 2511.6 2515.6 2462.2 2472.2 2485.7 2486.9 2499.6 2430.8 2457.7 2460.2 2460.4 2471.8 2418.3 2425.4 2437.3 2466.8 2538.3 11670.3 11758.8 11771.4 11790.4 11805.1 6357.6 6643.2 6643.5 6645.5 6652.9 4068.4 4252.4 4305.2 4324.2 4348.8 1167.0 1239.8 1242.4 1243.1 1266.6 14533.7 14765.6 14843.3 16086.1 16108.9 4845.6 5313.7 5327.1 5408.3 5570.7 9956.6 11386.0 11695.8 11701.6 11702.3 6310.1 6429.3 6434.4 6437.8 6442.2 5983.0 6016.8 6056.5 6121.8 6125.7 3908.0 4308.5 4357.7 4410.5 4412.6 1231.3 1255.6 1265.0 1266.3 1270.5 760.2 768.7 794.6 797.2 798.0 6064.5 6676.4 6960.2 6998.3 7015.3 881.5 927.7 930.7 930.8 931.4 3915.5 3923.8 3971.7 4369.3 4457.2 13565.6 14980.5 14998.0 15012.7 15041.2 12060.9 14919.3 15189.0 15194.9 15200.5 8351.7 8619.6 8628.1 8692.9 8736.4 6094.9 6869.5 6983.9 7137.1 7150.5 13579.7 13710.8 13829.0 14051.0 14069.6 11595.7 13877.4 13880.4 13963.9 14081.6 9260.9 9574.4 9580.2 9588.9 9598.4 9477.3 10633.3 10774.2 10779.7 10782.1 8904.2 9226.3 9244.3 9261.5 9262.0 9006.8 9030.4 9067.0 9115.0 9132.9 8178.1 8193.5 8200.9 8281.5 8290.8 4710.7 5980.2 6058.9 6189.3 6212.9 5579.0 6068.2 6138.8 6186.6 6290.9 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | [#4] prototype.html Cycles:180 Average:5391.72 Median:6082.51 stddev:1671.28 (27.5%) stddev-sans-first:1633.16 03:57:37 INFO - PID 1673 | Values: 383.8 397.2 399.2 400.2 408.0 4912.1 6033.0 6084.8 6203.0 6347.8 5372.1 5893.6 6173.3 6193.9 6212.5 4432.6 4514.3 4560.7 4576.7 4580.5 4530.1 4547.9 4564.4 4576.2 4582.7 3361.0 4494.8 4496.7 4514.9 4517.9 4516.7 4520.9 4537.5 4541.8 4546.0 3204.4 3305.5 3810.9 3889.5 3895.9 6050.4 6082.2 6082.8 6095.6 6107.0 5422.8 5426.6 5430.0 5434.9 5447.2 5949.7 6272.7 6543.8 6551.4 6672.9 4391.6 4394.3 4399.0 4400.0 4402.1 5527.0 5794.5 6500.8 7016.9 7117.2 3696.9 4281.9 4369.5 4391.0 4400.7 4492.3 4504.9 4509.5 4511.0 4525.6 5301.8 5340.9 5351.5 5372.5 5465.9 6619.6 6729.2 6738.6 6744.8 6753.8 1747.5 1762.2 1763.8 1764.8 1769.6 1181.7 1219.8 1227.0 1235.2 1242.6 6256.4 6280.7 6316.0 6328.8 6333.5 6304.6 6854.2 6953.7 6982.5 7039.9 5458.5 5824.7 6279.6 6307.2 6310.4 3060.4 3635.7 3636.5 3646.3 3655.2 6061.7 6172.0 6761.4 7125.9 7130.1 6532.5 6621.6 7148.0 7158.2 7165.7 5233.8 6524.5 6753.0 6977.9 7146.7 5745.5 6773.0 6964.4 6990.6 6992.7 5880.7 6318.7 6815.4 6837.4 6849.5 6730.8 7073.8 7107.0 7127.6 7129.4 4960.4 4999.6 5003.0 5003.9 5008.6 5605.8 6379.9 6921.6 7005.6 7042.5 6659.7 6683.9 6714.3 6715.7 6808.0 6709.2 6732.2 6733.8 6741.3 6792.7 6554.5 6572.6 6581.3 6587.2 6593.3 6241.3 6446.2 6703.8 6725.8 6756.4 6498.9 6519.0 6531.7 6536.7 6561.2 03:57:37 INFO - PID 1673 | 03:57:37 INFO - PID 1673 | [#5] yui.html Cycles:180 Average:25962.37 Median:16895.04 stddev:41752.11 (247.1%) stddev-sans-first:41826.29 03:57:37 INFO - PID 1673 | Values: 743.0 808.5 827.1 833.5 853.0 16911.5 17403.1 17419.1 17446.2 17450.7 14061.4 15479.0 15820.8 16538.2 16664.8 945.7 993.3 996.0 997.3 999.5 999.7 1003.1 1004.1 1004.7 1016.9 985.7 1035.6 1037.4 1038.4 1039.0 991.8 1016.0 1029.8 1030.0 1030.4 747.2 754.9 757.8 758.1 759.2 16878.6 17148.8 17375.3 17377.2 17560.7 17682.9 17826.3 17874.1 18198.6 18235.4 12900.6 14618.3 14730.3 14913.3 14937.6 3561.2 3572.7 3593.4 3597.5 3818.8 139699.0 142355.0 142690.0 142978.0 143874.0 13413.3 13628.7 13746.7 13851.0 13868.7 26443.9 26675.5 26905.5 27203.2 27268.2 7345.1 7591.3 7810.8 8003.3 8007.7 3426.6 3932.5 4002.8 4025.0 4040.8 4325.8 4378.7 4413.8 4432.8 4459.8 849.1 858.6 866.7 870.9 871.6 578.3 612.0 653.2 658.3 692.8 9697.9 10130.5 12234.2 12449.4 12501.3 654.9 655.6 664.0 669.1 679.3 5469.6 5601.3 5817.7 5881.6 5896.2 152521.7 154889.0 156162.0 160451.0 160671.0 130848.0 134560.3 134749.3 135530.0 135970.3 115916.4 116080.0 117036.0 117336.0 117744.0 11101.1 11604.4 11637.1 11667.7 11717.5 39301.4 40935.0 47091.8 47451.0 48090.0 62791.0 63004.4 63265.7 63330.7 64345.0 14300.5 14310.4 14623.7 14736.7 14760.5 13412.7 15671.5 15985.6 16086.0 16288.9 14992.9 15052.0 15138.0 15264.8 15280.6 14521.6 14662.1 15067.4 15131.2 15200.1 14370.6 14447.4 14525.9 14650.9 14746.5 8792.9 10855.1 10897.9 11018.2 11041.8 11099.0 11435.5 11898.4 12039.7 12176.5 03:57:37 INFO - PID 1673 | -------- Summary: end -------- 03:57:37 INFO - PID 1673 | 03:57:37 INFO - TEST-INFO | 1673: exit 0 03:57:37 INFO - TEST-OK | dromaeo_css | took 1142853ms 03:57:37 INFO - TEST-START | kraken 03:57:37 INFO - operating with platform_type : mac_ 03:57:37 INFO - Initialising browser for kraken test... 03:57:37 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpnnddqP/profile 03:57:37 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 03:58:11 INFO - TEST-INFO | 1686: killed by SIGTERM 03:58:11 INFO - Browser initialized. 03:58:11 INFO - Running cycle 1/1 for kraken test... 03:58:11 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpnnddqP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:13 INFO - PID 1690 | 2017-05-31 03:58:13.714 plugin-container[1691:11222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 03:58:13 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:13 INFO - PID 1690 | 2017-05-31 03:58:13.715 plugin-container[1691:11222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9913, name = 'com.apple.CFPasteboardClient' 03:58:13 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:13 INFO - PID 1690 | 2017-05-31 03:58:13.715 plugin-container[1691:11222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:58:14 INFO - PID 1690 | 2017-05-31 03:58:14.183 plugin-container[1692:11270] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 03:58:14 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:14 INFO - PID 1690 | 2017-05-31 03:58:14.183 plugin-container[1692:11270] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 03:58:14 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:14 INFO - PID 1690 | 2017-05-31 03:58:14.183 plugin-container[1692:11270] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:58:23 INFO - PID 1690 | RSS: Main: 268722176 03:58:23 INFO - PID 1690 | 03:58:26 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 03:58:27 INFO - PID 1690 | RSS: Main: 276676608 03:58:27 INFO - PID 1690 | 03:58:29 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 03:58:30 INFO - PID 1690 | RSS: Main: 284364800 03:58:30 INFO - PID 1690 | 03:58:33 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 03:58:34 INFO - PID 1690 | RSS: Main: 282185728 03:58:34 INFO - PID 1690 | 03:58:36 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 03:58:37 INFO - PID 1690 | RSS: Main: 284151808 03:58:37 INFO - PID 1690 | 03:58:39 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:58:39 INFO - PID 1690 | RSS: Main: 285147136 03:58:39 INFO - PID 1690 | 03:58:42 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 03:58:43 INFO - PID 1690 | RSS: Main: 289169408 03:58:43 INFO - PID 1690 | 03:58:46 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 03:58:46 INFO - PID 1690 | RSS: Main: 289345536 03:58:46 INFO - PID 1690 | 03:58:49 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 03:58:50 INFO - PID 1690 | RSS: Main: 289509376 03:58:50 INFO - PID 1690 | 03:58:51 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:58:52 INFO - PID 1690 | RSS: Main: 289046528 03:58:52 INFO - PID 1690 | 03:58:54 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:58:55 INFO - PID 1690 | RSS: Main: 289206272 03:58:55 INFO - PID 1690 | 03:58:57 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:58:58 INFO - PID 1690 | RSS: Main: 289124352 03:58:58 INFO - PID 1690 | 03:59:00 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:59:01 INFO - PID 1690 | RSS: Main: 289234944 03:59:01 INFO - PID 1690 | 03:59:04 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:59:05 INFO - PID 1690 | RSS: Main: 289587200 03:59:05 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 03:59:07 INFO - PID 1690 | RSS: Main: 291282944 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | __start_tp_report 03:59:07 INFO - PID 1690 | _x_x_mozilla_page_load 03:59:07 INFO - PID 1690 | _x_x_mozilla_page_load_details 03:59:07 INFO - PID 1690 | |i|pagename|runs| 03:59:07 INFO - PID 1690 | |0;ai-astar;102;115;113;114;115;113;116;114;114;116 03:59:07 INFO - PID 1690 | |1;audio-beat-detection;142;113;112;140;129;144;96;139;139;97 03:59:07 INFO - PID 1690 | |2;audio-dft;193;205;193;209;213;214;254;237;286;204 03:59:07 INFO - PID 1690 | |3;audio-fft;83;85;86;117;79;59;62;60;82;83 03:59:07 INFO - PID 1690 | |4;audio-oscillator;85;73;106;103;101;77;90;71;69;91 03:59:07 INFO - PID 1690 | |5;imaging-gaussian-blur;89;89;87;88;90;89;94;88;95;88 03:59:07 INFO - PID 1690 | |6;imaging-darkroom;97;96;96;96;98;95;95;97;97;100 03:59:07 INFO - PID 1690 | |7;imaging-desaturate;90;94;93;91;93;91;90;95;112;101 03:59:07 INFO - PID 1690 | |8;json-parse-financial;51;52;52;54;52;52;52;53;52;53 03:59:07 INFO - PID 1690 | |9;json-stringify-tinderbox;56;60;57;67;61;65;55;61;66;88 03:59:07 INFO - PID 1690 | |10;stanford-crypto-aes;85;87;87;88;86;97;88;91;139;91 03:59:07 INFO - PID 1690 | |11;stanford-crypto-ccm;150;141;177;147;148;146;139;138;141;154 03:59:07 INFO - PID 1690 | |12;stanford-crypto-pbkdf2;232;213;245;240;181;189;185;188;189;189 03:59:07 INFO - PID 1690 | |13;stanford-crypto-sha256-iterative;67;69;66;84;102;90;91;95;92;69 03:59:07 INFO - PID 1690 | __end_tp_report 03:59:07 INFO - PID 1690 | __start_cc_report 03:59:07 INFO - PID 1690 | _x_x_mozilla_cycle_collect,1190 03:59:07 INFO - PID 1690 | __end_cc_report 03:59:07 INFO - PID 1690 | __startTimestamp1496228347601__endTimestamp 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | ------- Summary: start ------- 03:59:07 INFO - PID 1690 | Number of tests: 14 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.50 stddev:4.08 (3.6%) stddev-sans-first:1.13 03:59:07 INFO - PID 1690 | Values: 102.0 115.0 113.0 114.0 115.0 113.0 116.0 114.0 114.0 116.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:141.00 stddev:18.92 (13.4%) stddev-sans-first:19.05 03:59:07 INFO - PID 1690 | Values: 142.0 113.0 112.0 140.0 129.0 144.0 96.0 139.0 139.0 97.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#2] audio-dft Cycles:10 Average:220.80 Median:213.50 stddev:29.70 (13.9%) stddev-sans-first:29.75 03:59:07 INFO - PID 1690 | Values: 193.0 205.0 193.0 209.0 213.0 214.0 254.0 237.0 286.0 204.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#3] audio-fft Cycles:10 Average:79.60 Median:82.50 stddev:17.05 (20.7%) stddev-sans-first:18.04 03:59:07 INFO - PID 1690 | Values: 83.0 85.0 86.0 117.0 79.0 59.0 62.0 60.0 82.0 83.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:75.00 stddev:13.81 (18.4%) stddev-sans-first:14.64 03:59:07 INFO - PID 1690 | Values: 85.0 73.0 106.0 103.0 101.0 77.0 90.0 71.0 69.0 91.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.00 stddev:2.67 (3.0%) stddev-sans-first:2.82 03:59:07 INFO - PID 1690 | Values: 89.0 89.0 87.0 88.0 90.0 89.0 94.0 88.0 95.0 88.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.50 stddev:1.49 (1.5%) stddev-sans-first:1.58 03:59:07 INFO - PID 1690 | Values: 97.0 96.0 96.0 96.0 98.0 95.0 95.0 97.0 97.0 100.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:92.00 stddev:6.80 (7.4%) stddev-sans-first:6.97 03:59:07 INFO - PID 1690 | Values: 90.0 94.0 93.0 91.0 93.0 91.0 90.0 95.0 112.0 101.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.73 03:59:07 INFO - PID 1690 | Values: 51.0 52.0 52.0 54.0 52.0 52.0 52.0 53.0 52.0 53.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:63.00 stddev:9.52 (15.1%) stddev-sans-first:9.70 03:59:07 INFO - PID 1690 | Values: 56.0 60.0 57.0 67.0 61.0 65.0 55.0 61.0 66.0 88.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:88.00 stddev:16.22 (18.4%) stddev-sans-first:16.88 03:59:07 INFO - PID 1690 | Values: 85.0 87.0 87.0 88.0 86.0 97.0 88.0 91.0 139.0 91.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:147.50 stddev:11.38 (7.7%) stddev-sans-first:12.05 03:59:07 INFO - PID 1690 | Values: 150.0 141.0 177.0 147.0 148.0 146.0 139.0 138.0 141.0 154.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.10 Median:201.00 stddev:25.06 (12.5%) stddev-sans-first:24.61 03:59:07 INFO - PID 1690 | Values: 232.0 213.0 245.0 240.0 181.0 189.0 185.0 188.0 189.0 189.0 03:59:07 INFO - PID 1690 | 03:59:07 INFO - PID 1690 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.50 Median:87.00 stddev:13.48 (15.5%) stddev-sans-first:13.07 03:59:07 INFO - PID 1690 | Values: 67.0 69.0 66.0 84.0 102.0 90.0 91.0 95.0 92.0 69.0 03:59:07 INFO - PID 1690 | -------- Summary: end -------- 03:59:07 INFO - PID 1690 | 03:59:08 INFO - TEST-INFO | 1690: exit 0 03:59:08 INFO - TEST-OK | kraken | took 90556ms 03:59:08 INFO - SUITE-END | took 1233s 03:59:08 INFO - Completed test suite (00:20:33) 03:59:08 INFO - javascript benchmark 03:59:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8194.999255459248, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3603.9639603603955, 3606.6934310583597, 3632.4551317302416, 3658.670719635233, 3665.9633403665957, 6148.047052706826, 6148.600340977833, 6248.875022499542, 6256.655883926388, 6276.340984196662, 6227.53293502988, 6249.468795152417, 6250.749970001195, 6259.217597800275, 6319.0, 5427.375851777037, 5521.723913804314, 5526.226328314039, 5528.5024347808685, 5585.553155747551, 5581.49766521013, 5586.217929489874, 5648.519875810561, 5654.6041777075625, 5716.028275193227, 4614.630829533646, 5407.891842163135, 5559.054960656698, 5580.497755202051, 5637.492625663689, 4554.3851580036935, 5468.7265636718, 5549.19536667181, 5594.049011668027, 5597.076482380402, 4521.367008618796, 5477.287952566181, 5484.698341591211, 5530.419305972855, 5543.805966791143, 6258.06129080637, 6284.465820405271, 6302.401271879165, 6308.968455157741, 6314.147590075373, 6134.263888333384, 6617.86764264712, 6709.7651582194385, 6766.120404347453, 6805.625690587065, 6068.877257707294, 6716.328367163244, 6923.723051077951, 6930.480213983921, 6938.757143500004, 6348.111264423031, 6354.9364506354805, 6355.396237357444, 6361.332060133712, 6393.936060639334, 6775.3902148805855, 6866.931330686729, 6985.231624521299, 7040.823979400556, 7055.3650171484815, 6427.871442571216, 6492.480601551864, 6550.246721627074, 6611.570247933869, 6633.071370008202, 6834.179898412222, 6851.2121106072445, 6868.072810170663, 6871.278515755874, 6871.484638652121, 6641.468682505388, 6749.527533072736, 6749.797506074826, 6767.898481522781, 6770.492213084039, 6445.097686323918, 6538.346165383518, 6645.867082658319, 6717.764878229238, 6793.558418702769, 5353.982743278764, 5359.839204823861, 5364.275822763877, 5374.301340825746, 5410.3778065522965, 4781.713097214179, 4786.497417771154, 4792.041591681608, 4811.398575178103, 4818.6626936114135, 5610.354809196913, 5944.405559444021, 6281.905771413432, 6315.842103947346, 6348.809535713844, 6877.862442751117, 6900.3444672756, 6907.309269073153, 6916.723331066701, 6944.513884028171, 6302.0, 6347.301796802348, 6355.0, 6367.586106903036, 6425.903611445832, 5818.185454036438, 5869.06095024794, 5959.4934430573885, 6005.609635373687, 6046.455818976313, 7015.403690686247, 7032.578045317297, 7035.33164349386, 7067.293270672892, 7073.115860517435, 6747.932520674731, 6913.723451061901, 6915.066466027022, 6937.375636192565, 6940.201876784235, 5643.407442218508, 6374.330695276929, 6790.490713196629, 7025.367716905503, 7039.120109986252, 6676.232233293039, 6707.731690732511, 6721.126253587003, 6767.695453704693, 6798.490113241428, 5762.567807414377, 6913.3777959983845, 6930.688119034757, 6937.930620693728, 6947.5831450113155, 6430.742770289136, 6520.021996700533, 6575.671216439255, 6601.0, 6664.067030615621, 6634.933650663625, 6677.3656502632175, 6680.732770689118, 6686.0973768540625, 6718.328167183243, 6447.903281450688, 6557.540972131905, 6578.243501997332, 6593.736250549924, 6608.173978252718, 6475.0, 6509.7396104155305, 6655.168103987002, 6693.029510721016, 6719.596824190564, 6421.903671445026, 6455.2576453708425, 6498.3826536478955, 6501.707423166064, 6525.836854078686], "value": 6170.686721596984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.1684822512251, 824.2087595907929, 849.5667209723043, 1258.7482503499298, 1329.5080820096566, 38367.61632383679, 39442.60557394425, 39517.01207469815, 39549.406758898636, 39590.80204598977, 39536.0, 39865.20269594606, 39914.800425997826, 39957.800210998976, 40028.39957400641, 7864.837169778328, 8044.436889417743, 8169.223923727236, 8380.706675266367, 8383.245507904287, 8258.83482330353, 8407.62165702545, 8417.410781245315, 8433.662653493853, 8448.324134069291, 8348.457350272243, 8361.0, 8456.78858028551, 8461.492310461392, 8487.193716596945, 8379.83240335193, 8411.915880841174, 8430.915690843074, 8434.325253979667, 8436.873446898302, 8181.550014749187, 8232.83534329316, 8239.093699693029, 8246.958765206136, 8274.958625206895, 30887.918922837816, 37373.43939840904, 37611.43582846259, 37613.43579846305, 37711.43432848482, 3582.5700915889997, 3599.5860476045327, 3649.434337677638, 3652.36083685354, 3654.3787556115526, 30314.090577282717, 30444.0, 30474.085777426713, 30476.54285185724, 30478.54282185769, 3047.405755877605, 3101.6433110192165, 3111.548825420324, 3118.1425108095227, 3130.906072817819, 236856.0, 237522.0, 239040.0, 296925.0, 298783.0, 39631.20737585232, 48676.78308042327, 48698.02603947972, 48734.75632621814, 48840.0, 49105.75447122813, 49752.75123624359, 50671.493285067416, 50871.491285086675, 51054.234186487236, 14011.43954241819, 14147.64630884236, 14192.361343739556, 14219.146851188962, 14232.430702771773, 11248.94375528117, 11297.830532542019, 11311.660650180507, 11317.603883864025, 11394.886051139549, 11898.929096381367, 13493.527726529524, 14104.871610271153, 14111.506097286545, 14181.85818141826, 5273.44628813969, 5674.460926212003, 6056.0, 6100.389961003864, 6113.174721412642, 1009.6920439265947, 1036.3988886445843, 1044.9007344302279, 1064.4411683866033, 1075.3655343347461, 18979.33572324962, 23516.7648323518, 23620.763792361857, 24789.62815557768, 24837.254882353558, 1111.1166622535104, 1112.9276597021167, 1146.140394703972, 1148.8563929508812, 1162.68607475981, 10376.584936602603, 11654.184207105422, 12549.874501255053, 12805.167664101804, 12832.0, 268664.0, 331151.0, 332168.0, 332440.0, 332895.0, 54036.45963540393, 54091.45908540943, 54495.63760906009, 54549.0, 54555.6361090968, 1479.474786450816, 1516.066411493741, 1523.8095238095239, 1529.3041666041925, 1543.614096475881, 23969.640455392833, 24108.51782964366, 24154.154604588755, 24236.51526969486, 24518.63222051635, 38510.80744596259, 38604.0, 38636.2272754549, 38761.224775504896, 38898.0, 280898.19101809536, 282938.0, 284123.73814393213, 284758.5762071259, 285389.0, 34933.47599785955, 35914.820425897706, 35921.46117808287, 36120.81939590285, 36160.81919590385, 43265.0, 43695.34456983084, 45106.097878042914, 45207.773961129984, 45327.54672453233, 28708.421036843174, 30395.784168633007, 30543.0, 31312.0, 32357.029289120423, 28413.573796393484, 30411.391772164876, 30651.2337191572, 30681.53977690381, 30718.692813071582, 32721.509177361884, 34637.48043779396, 36094.4585831218, 36664.0, 37404.81297593604, 30014.54978175373, 30401.239969000068, 31288.530672039484, 31300.686993129777, 31444.371112577162, 22895.65656515187, 22998.540029199656, 23308.883455582614, 24583.13959011426, 24826.50346993014], "value": 17490.60161567531, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.91729819439723, 393.33133672756315, 418.5354256774979, 431.8812326610184, 455.6150053205043, 21596.676049859245, 22352.3294301171, 22384.77615223847, 22442.214522491715, 22477.32568022958, 24282.878585607046, 25214.74785252147, 25323.62014569783, 25334.746652533468, 25353.49293014143, 7063.7174513019545, 7186.281371862824, 7218.350348468637, 7222.988781570584, 7241.565506069653, 7086.255943125975, 7150.964245178767, 7157.2842715728275, 7162.713491460336, 7176.964115179417, 6844.7262109515805, 7079.823004424905, 7090.751823686172, 7104.0, 7152.034475345814, 7052.471064670144, 7094.71621135154, 7110.893336599956, 7124.964375178117, 7139.750108746195, 6951.548149370275, 6964.477664175207, 6970.0, 6971.0, 6983.092198014277, 28663.710133044064, 28763.424731505464, 30407.847960760275, 30743.69256307453, 30835.0, 2238.5858616156065, 2272.9204477843195, 2277.3281881844896, 2282.258266063536, 2288.2448791898632, 16874.83125168745, 19987.300444484517, 20352.69470957937, 20614.072366743534, 20668.79331206684, 2281.863088214712, 2299.7700229977036, 2354.505553833697, 2355.4935688826986, 2389.629607410854, 123499.0, 149589.0, 167391.0, 190909.0, 192631.0368448163, 25717.48565028689, 25735.0, 27283.181504554894, 27780.58329125065, 27886.72113278841, 34828.30343393118, 42888.57111428908, 43205.567944320785, 43268.56731432708, 43334.0, 10799.352038877692, 10819.729506762395, 10878.673639790819, 10899.727506812393, 10916.0, 8493.830123397498, 8552.957235213784, 8576.914230857736, 8656.523891186045, 8679.392442529088, 11623.012043976187, 12082.408684699934, 12252.754944901051, 12276.0, 12377.566785162477, 4345.283028300295, 4907.901841963141, 4925.2119660854805, 4926.881440980413, 4934.703917764946, 888.3692578269372, 975.6292608808608, 982.1259079419323, 1011.7622358745836, 1044.5821671331528, 21188.0, 21444.463888402603, 21724.022418991182, 21913.342599722033, 21978.780212197675, 873.689840106753, 926.6849271247808, 1011.3780025284398, 1055.6094245129352, 1057.9365238085738, 10447.68656940293, 10718.785624287471, 10744.656917885264, 11008.284461509977, 11288.153388495895, 554664.0, 556007.4399256037, 556292.0, 564725.0, 566586.167069162, 155394.0, 158197.62703559455, 158906.20546897192, 159459.0, 159648.0, 1123.6797512708838, 1150.2408410449063, 1221.4869754703045, 1234.1916044990546, 1237.418413345744, 21896.452588684773, 22229.555408891407, 23276.0, 23518.88240558786, 23626.52746945086, 68417.0, 69135.92592222242, 70624.0, 72503.0, 72685.0, 92068.0, 92099.53950230205, 92335.07664923533, 92426.53786731024, 92893.53553232191, 9944.552495137881, 10232.948835255776, 10378.221633377376, 10384.636537721144, 10385.584576617153, 17120.143992800062, 18453.354132605295, 18512.907435462737, 18576.25694972186, 18632.906835466278, 14885.330160143036, 15295.694086118438, 15302.234888255765, 15448.073115613099, 15449.922750386626, 13691.452341906212, 13949.23279219612, 14008.649783755487, 14078.084924480285, 14093.013489056078, 14394.568162955127, 15329.846701532842, 15531.84468155304, 15644.061356318658, 15659.451919182773, 10312.535935883054, 10342.844857327296, 10437.791244174921, 10458.947705261426, 10492.737681558023, 11127.054200393057, 14015.36930838135, 14404.279786010447, 14804.963652544218, 14964.0], "value": 12602.769621700327, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1253.8056601226813, 1255.9686007849805, 1429.463951018368, 1433.412300956608, 1438.8057612222347, 11506.654800355993, 11605.883941160586, 11907.76184476311, 11917.165798394106, 12351.444185011673, 11632.476538555764, 11959.043276537877, 11959.940200299008, 11970.700732481693, 11982.460789264482, 2269.3305474884864, 2299.2067736630834, 2312.4218945263683, 2314.918977835776, 2319.512902290521, 2465.642481840132, 2509.4479214572766, 2509.912153074643, 2511.635812807142, 2515.55977703902, 2462.150558057467, 2472.196536125757, 2485.7389974052735, 2486.9378265543323, 2499.575072237724, 2430.805535557151, 2457.7050753909557, 2460.1635443949053, 2460.3972026853444, 2471.765182307678, 2418.3107814273026, 2425.3815277104227, 2437.305367970138, 2466.7656572625574, 2538.2765911715255, 11670.299782013022, 11758.823617645741, 11771.352575608338, 11790.410479476077, 11805.055595552336, 6357.586756860789, 6643.169603799525, 6643.534952553323, 6645.501587380966, 6652.8669426611195, 4068.4304197412384, 4252.4046633471335, 4305.182015417061, 4324.178406102862, 4348.826046958118, 1166.9824952625718, 1239.7582471418077, 1242.4160644497072, 1243.1422318600228, 1266.645339304996, 14533.70932581353, 14765.630859228606, 14843.332050057774, 16086.115263660495, 16108.919455402765, 4845.63657725672, 5313.681179129265, 5327.147656374961, 5408.269883565747, 5570.693611851387, 9956.551955162035, 11386.032187264176, 11695.824562631567, 11701.590444334408, 11702.2978621283, 6310.1481300024825, 6429.32492088324, 6434.388733070351, 6437.839054023687, 6442.226932768098, 5983.0, 6016.759329626766, 6056.485198758067, 6121.816345509642, 6125.693715314305, 3907.9609203908167, 4308.482982042175, 4357.694961352675, 4410.536893626123, 4412.646988240933, 1231.2735486063266, 1255.5731051442554, 1264.9620511384674, 1266.265565971734, 1270.4664041102758, 760.152430040512, 768.7040489411617, 794.5709316968772, 797.2067792546337, 797.9920200797918, 6064.4845188158615, 6676.432503237279, 6960.234374218832, 6998.265182155902, 7015.3023324914975, 881.4887365328094, 927.7495076329353, 930.7393929699695, 930.7580029192324, 931.4271722890373, 3915.47141135943, 3923.7841918694176, 3971.7219794614675, 4369.257226271541, 4457.175422546839, 13565.59303220905, 14980.475683351031, 14998.025128366622, 15012.699746005019, 15041.172735499653, 12060.939695301642, 14919.328630211667, 15189.012714173543, 15194.924025379802, 15200.46798362052, 8351.74723791424, 8619.569021548898, 8628.094050124897, 8692.869606955901, 8736.432131911533, 6094.878102438015, 6869.484788640772, 6983.917492788626, 7137.10786151731, 7150.499465037397, 13579.728405431639, 13710.794338084737, 13829.0, 14051.0, 14069.577912662637, 11595.710107247387, 13877.375518101508, 13880.375383107583, 13963.860361396257, 14081.577552673436, 9260.907390926004, 9574.377665451724, 9580.233581313618, 9588.904110958802, 9598.376105553116, 9477.289203309918, 10633.255672102878, 10774.24580279373, 10779.67660970172, 10782.137429005817, 8904.19862212404, 9226.261899048195, 9244.260459163117, 9261.53692315395, 9261.953690231505, 9006.819863602823, 9030.413023153475, 9067.0, 9114.99735029133, 9132.908670913206, 8178.1004089548915, 8193.46742461762, 8200.917990820015, 8281.503109813431, 8290.792730181794, 4710.740909250024, 5980.192673988956, 6058.878822423615, 6189.257289125334, 6212.937870621236, 5579.005138951287, 6068.241469816273, 6138.815835524942, 6186.628802271878, 6290.874182516415], "value": 5331.829431279783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.8464614154338, 397.19766072533486, 399.19163693520636, 400.15367497742267, 408.04313883942143, 4912.06670732561, 6032.969835150823, 6084.756609735611, 6202.968985155079, 6347.777827776029, 5372.086745253307, 5893.58744887858, 6173.290071641763, 6193.876122477548, 6212.471939885104, 4432.601065904068, 4514.322851572258, 4560.657950653697, 4576.725396476222, 4580.541945805426, 4530.093981203756, 4547.886302842422, 4564.383808185902, 4576.222042252825, 4582.656300777438, 3361.0, 4494.842680506183, 4496.730196188223, 4514.887127821798, 4517.944335327435, 4516.6838321317355, 4520.9095818083615, 4537.478190008158, 4541.750203738793, 4545.977270113662, 3204.3911656785135, 3305.5372247885316, 3810.8567429770956, 3889.5138107736534, 3895.9026024349337, 6050.428567858687, 6082.209312789353, 6082.817515474543, 6095.603785753912, 6106.969465152691, 5422.779874528239, 5426.565874730051, 5430.0, 5434.8913021739345, 5447.155690867882, 5949.732262048218, 6272.714093610799, 6543.756686229601, 6551.410373066399, 6672.932330827093, 4391.6486681065435, 4394.318880573484, 4398.966242932908, 4399.956000439986, 4402.119576084764, 5526.977509160778, 5794.5074668653215, 6500.772472963471, 7016.859662806715, 7117.217106118323, 3696.9445458318146, 4281.908113431117, 4369.497507786646, 4390.956090439118, 4400.669949753781, 4492.281235002384, 4504.851262927998, 4509.549045095529, 4511.0, 4525.646663734905, 5301.787928482895, 5340.91988620171, 5351.51836334724, 5372.516473517402, 5465.890682186334, 6619.602823830489, 6729.192496900306, 6738.561993470409, 6744.831379215559, 6753.797386078426, 1747.5281673947964, 1762.242235838576, 1763.7530745695612, 1764.80587135415, 1769.6018395860829, 1181.7222952606303, 1219.841420615332, 1226.957056503018, 1235.184778046485, 1242.6334231401759, 6256.405641464054, 6280.685965701697, 6316.0, 6328.841778955563, 6333.46165575922, 6304.558680892385, 6854.2117656470145, 6953.687084081127, 6982.546134501241, 7039.894401583981, 5458.4541545845095, 5824.737886795105, 6279.591826531261, 6307.180066591405, 6310.43206111443, 3060.4185204811015, 3635.654612811779, 3636.5272514573467, 3646.252518233756, 3655.1958569114754, 6061.666608336495, 6172.037422037447, 6761.391474767294, 7125.92874071263, 7130.108736407949, 6532.542722009413, 6621.569597976212, 7147.963545286008, 7158.2483839197175, 7165.713371465187, 5233.764480598382, 6524.510661700391, 6752.953292239711, 6977.89533157003, 7146.749863754743, 5745.482906538265, 6773.0, 6964.423163429564, 6990.61551614666, 6992.650367481708, 5880.676562789087, 6318.652474113968, 6815.42068924147, 6837.384635382839, 6849.452043836382, 6730.831729206809, 7073.752418665322, 7106.964465177641, 7127.643617819192, 7129.429645628234, 4960.355153829979, 4999.600031997505, 5003.0, 5003.949170674054, 5008.574271186844, 5605.803796867253, 6379.936200637934, 6921.584704917721, 7005.649717514206, 7042.471814613822, 6659.733610655519, 6683.8997415039785, 6714.261431242657, 6715.731370745116, 6808.0, 6709.161354830646, 6732.192136943598, 6733.764318248838, 6741.258461569321, 6792.660366981532, 6554.508411869224, 6572.572782769104, 6581.276059633532, 6587.176602924635, 6593.274739778716, 6241.344658810759, 6446.194225721785, 6703.832404189934, 6725.798226053226, 6756.358145976124, 6498.902516462352, 6519.035082899284, 6531.673416329259, 6536.673166341759, 6561.212654481493], "value": 4887.3394556704925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.9591372474515, 808.4623725222727, 827.1439060572312, 833.5332213960181, 853.0019876744207, 16911.492655220347, 17403.12984350784, 17419.129043547837, 17446.214920328584, 17450.738238926428, 14061.367238474268, 15478.993865398741, 15820.841791582081, 16538.173091345445, 16664.83335166648, 945.7021038372891, 993.3394292795282, 995.9751006224866, 997.3118548201754, 999.535216124502, 999.6950929966357, 1003.0922015575916, 1004.1364426593124, 1004.7287232447235, 1016.8627235323211, 985.6845809340978, 1035.5961175141701, 1037.4294138223984, 1038.4288641247322, 1038.9636362727306, 991.8065977134462, 1016.0246163682799, 1029.8300780371267, 1030.0369154840212, 1030.4178139351243, 747.1706405889458, 754.890540871576, 757.7764559454974, 758.135725273189, 759.2104211619909, 16878.578035549086, 17148.828511714848, 17375.30498780047, 17377.218025188897, 17560.736588951175, 17682.911585442118, 17826.286948522044, 17874.106294685214, 18198.63602727938, 18235.361762338256, 12900.612981610566, 14618.342174602169, 14730.263486825615, 14913.328900199516, 14937.551873443705, 3561.1631266652316, 3572.7499075064757, 3593.389123848952, 3597.460380942853, 3818.7899665518385, 139699.0, 142355.0, 142690.0, 142978.0, 143874.0, 13413.262270575016, 13628.727425451634, 13746.65633359174, 13851.030427869953, 13868.722625547432, 26443.867780660974, 26675.46649067008, 26905.461890762468, 27203.183904482896, 27268.181954541396, 7345.118585769635, 7591.278828511283, 7810.804729881798, 8003.319717823933, 8007.718764997572, 3426.554547908806, 3932.528096628366, 4002.8198731057173, 4024.959750402517, 4040.818163182605, 4325.7620830853975, 4378.671599630041, 4413.845515406945, 4432.822687092545, 4459.7547134907245, 849.0999540487103, 858.6176256227469, 866.7009881590841, 870.9259712924472, 871.6339137562239, 578.2598274208935, 611.9877602447926, 653.2291895563282, 658.2857599504496, 692.8302565871394, 9697.9030209697, 10130.493475326204, 12234.204776689487, 12449.377531123408, 12501.312427816558, 654.8559316950359, 655.6295692933477, 663.970121344531, 669.1167658690482, 679.2629996453903, 5469.589780766459, 5601.29983752031, 5817.650940943557, 5881.617694849821, 5896.204012458349, 152521.71217431748, 154889.0, 156162.0, 160451.0, 160671.0, 130848.0, 134560.32719836337, 134749.32625337006, 135530.0, 135970.3201484006, 115916.42041789906, 116080.0, 117036.0, 117336.0, 117744.0, 11101.111911046935, 11604.419779010846, 11637.127215458706, 11667.708307292385, 11717.472713728073, 39301.41047884227, 40935.0, 47091.76454117707, 47451.0, 48090.0, 62791.0, 63004.36995629985, 63265.68367158135, 63330.68334658297, 64345.0, 14300.499482518062, 14310.427582896567, 14623.707525849637, 14736.705265894409, 14760.450152734238, 13412.664683382993, 15671.529854104396, 15985.600359991093, 16086.0, 16288.918555407146, 14992.850071499146, 15052.0, 15138.016028958082, 15264.847351526343, 15280.61798455003, 14521.636959075686, 14662.120272783666, 15067.397304108152, 15131.167785771448, 15200.087994720352, 14370.568882933529, 14447.422103115758, 14525.92737036308, 14650.853491464948, 14746.483873064391, 8792.912070879465, 10855.077318428022, 10897.945510272715, 11018.173636977099, 11041.779164416826, 11099.0, 11435.542578296774, 11898.405079745806, 12039.699007524532, 12176.45205965716], "value": 8544.913749982798, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 113.0, 114.0, 115.0, 113.0, 116.0, 114.0, 114.0, 116.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 112.0, 140.0, 129.0, 144.0, 96.0, 139.0, 139.0, 97.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 205.0, 193.0, 209.0, 213.0, 214.0, 254.0, 237.0, 286.0, 204.0], "value": 220.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 117.0, 79.0, 59.0, 62.0, 60.0, 82.0, 83.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 106.0, 103.0, 101.0, 77.0, 90.0, 71.0, 69.0, 91.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 87.0, 88.0, 90.0, 89.0, 94.0, 88.0, 95.0, 88.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 98.0, 95.0, 95.0, 97.0, 97.0, 100.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 93.0, 91.0, 93.0, 91.0, 90.0, 95.0, 112.0, 101.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 54.0, 52.0, 52.0, 52.0, 53.0, 52.0, 53.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 57.0, 67.0, 61.0, 65.0, 55.0, 61.0, 66.0, 88.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 88.0, 86.0, 97.0, 88.0, 91.0, 139.0, 91.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 141.0, 177.0, 147.0, 148.0, 146.0, 139.0, 138.0, 141.0, 154.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [232.0, 213.0, 245.0, 240.0, 181.0, 189.0, 185.0, 188.0, 189.0, 189.0], "value": 205.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 84.0, 102.0, 90.0, 91.0, 95.0, 92.0, 69.0], "value": 82.5, "unit": "score"}]}]} 03:59:08 INFO - Return code: 0 03:59:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:59:08 INFO - # TBPL SUCCESS # 03:59:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:59:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:59:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:59:08 INFO - 2017-05-31 03:59:08.924 screenresolution[1698:11649] starting screenresolution argv=screenresolution get 03:59:08 INFO - 2017-05-31 03:59:08.927 screenresolution[1698:11649] Display 0: 1600x1200x32@60 03:59:08 INFO - 2017-05-31 03:59:08.933 screenresolution[1699:11652] starting screenresolution argv=screenresolution list 03:59:08 INFO - Available Modes on Display 0 03:59:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:59:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:59:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:59:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:59:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:59:09 INFO - Intel Iris: 03:59:09 INFO - Chipset Model: Intel Iris 03:59:09 INFO - Type: GPU 03:59:09 INFO - Bus: Built-In 03:59:09 INFO - VRAM (Dynamic, Max): 1536 MB 03:59:09 INFO - Vendor: Intel (0x8086) 03:59:09 INFO - Device ID: 0x0a2e 03:59:09 INFO - Revision ID: 0x0009 03:59:09 INFO - Displays: 03:59:09 INFO - Display: 03:59:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:59:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:59:09 INFO - Display Serial Number: 1600x1200 60 03:59:09 INFO - Main Display: Yes 03:59:09 INFO - Mirror: Off 03:59:09 INFO - Online: Yes 03:59:09 INFO - Rotation: Supported 03:59:09 INFO - Return code: 0 03:59:09 INFO - Running post-action listener: _resource_record_post_action 03:59:09 INFO - [mozharness: 2017-05-31 10:59:09.168514Z] Finished run-tests step (success) 03:59:09 INFO - Running post-run listener: _resource_record_post_run 03:59:09 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 36287488; Write bytes: 572519424; Read time: 234; Write time: 1792 03:59:09 INFO - TinderboxPrint: CPU usage
53.2% 03:59:09 INFO - TinderboxPrint: I/O read bytes / time
36,287,488 / 234 03:59:09 INFO - TinderboxPrint: I/O write bytes / time
572,519,424 / 1,792 03:59:09 INFO - TinderboxPrint: CPU idle
2,350.0 (46.8%) 03:59:09 INFO - TinderboxPrint: CPU system
1,015.7 (20.2%) 03:59:09 INFO - TinderboxPrint: CPU user
1,659.9 (33.0%) 03:59:09 INFO - TinderboxPrint: Swap in / out
277,319,680 / 0 03:59:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244955648; Write bytes: 238524416; Read time: 17937; Write time: 510 03:59:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:59:09 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 32691200; Write bytes: 321682432; Read time: 196; Write time: 1239 03:59:09 INFO - Running post-run listener: _upload_blobber_files 03:59:09 INFO - Blob upload gear active. 03:59:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:59:09 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 03:59:09 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'] 03:59:09 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 03:59:10 INFO - (blobuploader) - INFO - Open directory for files ... 03:59:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:59:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:59:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:59:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:59:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:59:10 INFO - (blobuploader) - INFO - Done attempting. 03:59:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:59:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:59:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:59:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:59:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:59:11 INFO - (blobuploader) - INFO - Done attempting. 03:59:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:59:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:59:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:59:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:59:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:59:12 INFO - (blobuploader) - INFO - Done attempting. 03:59:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:59:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:59:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:59:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:59:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:59:14 INFO - (blobuploader) - INFO - Done attempting. 03:59:14 INFO - (blobuploader) - INFO - Iteration through files over. 03:59:14 INFO - Return code: 0 03:59:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:59:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:59:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8782614a1517aa5394fb050236f69a116e06998941c2f57390074346a3f4ded0a219fae3a5f4d5f10978e5db26dfe6e78c755aa6d23b969b69c3792b54113d6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a315175bf26e8b0937496ac8e0383e74ce119856ccc8c0149166b7f91a55806a5c749c243c8730c90dfbd70bf223d04077912b1fc443b66eb44fa0ce5c6a8365", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba56d13368fb8adbc161f7bcea2b1b61b6348d2392939f029f5dd1d88c7fe000626d41de0e1a1899d8d5cae25bd27ba8eb01193021432027f6fc7367726e2024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050310df227aae4e3285470df678648d9f9afdbe68155e26ea5c7dbcb497c3e7f6516b78f5826db081324b44afa813e137b1fac25dba6dc7fbdd9ec9f217927e"} 03:59:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:59:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:59:14 INFO - Contents: 03:59:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8782614a1517aa5394fb050236f69a116e06998941c2f57390074346a3f4ded0a219fae3a5f4d5f10978e5db26dfe6e78c755aa6d23b969b69c3792b54113d6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a315175bf26e8b0937496ac8e0383e74ce119856ccc8c0149166b7f91a55806a5c749c243c8730c90dfbd70bf223d04077912b1fc443b66eb44fa0ce5c6a8365", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba56d13368fb8adbc161f7bcea2b1b61b6348d2392939f029f5dd1d88c7fe000626d41de0e1a1899d8d5cae25bd27ba8eb01193021432027f6fc7367726e2024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050310df227aae4e3285470df678648d9f9afdbe68155e26ea5c7dbcb497c3e7f6516b78f5826db081324b44afa813e137b1fac25dba6dc7fbdd9ec9f217927e"} 03:59:14 INFO - Running post-run listener: copy_logs_to_upload_dir 03:59:14 INFO - Copying logs to upload dir... 03:59:14 INFO - mkdir: /builds/slave/test/build/upload/logs 03:59:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.706184 ========= master_lag: 0.60 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-31 03:59:15.349967) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 03:59:15.353666) ========= 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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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/8782614a1517aa5394fb050236f69a116e06998941c2f57390074346a3f4ded0a219fae3a5f4d5f10978e5db26dfe6e78c755aa6d23b969b69c3792b54113d6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a315175bf26e8b0937496ac8e0383e74ce119856ccc8c0149166b7f91a55806a5c749c243c8730c90dfbd70bf223d04077912b1fc443b66eb44fa0ce5c6a8365", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba56d13368fb8adbc161f7bcea2b1b61b6348d2392939f029f5dd1d88c7fe000626d41de0e1a1899d8d5cae25bd27ba8eb01193021432027f6fc7367726e2024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050310df227aae4e3285470df678648d9f9afdbe68155e26ea5c7dbcb497c3e7f6516b78f5826db081324b44afa813e137b1fac25dba6dc7fbdd9ec9f217927e"} build_url:https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011168 build_url: 'https://queue.taskcluster.net/v1/task/DYJrgKksRJCsy-V8NQyRFg/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/8782614a1517aa5394fb050236f69a116e06998941c2f57390074346a3f4ded0a219fae3a5f4d5f10978e5db26dfe6e78c755aa6d23b969b69c3792b54113d6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a315175bf26e8b0937496ac8e0383e74ce119856ccc8c0149166b7f91a55806a5c749c243c8730c90dfbd70bf223d04077912b1fc443b66eb44fa0ce5c6a8365", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba56d13368fb8adbc161f7bcea2b1b61b6348d2392939f029f5dd1d88c7fe000626d41de0e1a1899d8d5cae25bd27ba8eb01193021432027f6fc7367726e2024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050310df227aae4e3285470df678648d9f9afdbe68155e26ea5c7dbcb497c3e7f6516b78f5826db081324b44afa813e137b1fac25dba6dc7fbdd9ec9f217927e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 03:59:15.393944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:59:15.394516) ========= 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.hC5ZB2eYg4/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.yJeEA9KjaY/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.004825 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:59:15.445641) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 03:59:15.446186) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 03:59:15.446743) ========= ========= Total master_lag: 3.25 =========