builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0120 starttime: 1495876143.01 results: success (0) revision: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.014479) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.014904) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.015208) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.039058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.039423) ========= 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.84jE1ey9ki/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.nx2Fti5C6o/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-27 02:09:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-05-27 02:09:03 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100661 ========= master_lag: 0.49 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.630713) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.631034) ========= 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.84jE1ey9ki/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.nx2Fti5C6o/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.022379 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:03.674616) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-27 02:09:03.674945) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c --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 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.84jE1ey9ki/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.nx2Fti5C6o/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-27 02:09:03,766 truncating revision to first 12 chars 2017-05-27 02:09:03,767 Setting DEBUG logging. 2017-05-27 02:09:03,767 attempt 1/10 2017-05-27 02:09:03,767 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1c1bf54915bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-27 02:09:05,085 unpacking tar archive at: mozilla-central-1c1bf54915bb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.583103 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-27 02:09:05.279848) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:05.280176) ========= script_repo_revision: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:05.280532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:05.280799) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 02:09:05.299712) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-27 02:09:05.300098) ========= /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.84jE1ey9ki/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.nx2Fti5C6o/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:09:05 INFO - MultiFileLogger online at 20170527 02:09:05 in /builds/slave/test 02:09:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:09:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:09:05 INFO - {'append_to_log': False, 02:09:05 INFO - 'base_work_dir': '/builds/slave/test', 02:09:05 INFO - 'blob_upload_branch': 'Firefox', 02:09:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:09:05 INFO - 'branch': 'Firefox', 02:09:05 INFO - 'buildbot_json_path': 'buildprops.json', 02:09:05 INFO - 'config_files': ('talos/mac_config.py',), 02:09:05 INFO - 'default_actions': ('clobber', 02:09:05 INFO - 'read-buildbot-config', 02:09:05 INFO - 'download-and-extract', 02:09:05 INFO - 'populate-webroot', 02:09:05 INFO - 'create-virtualenv', 02:09:05 INFO - 'install', 02:09:05 INFO - 'run-tests'), 02:09:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:09:05 INFO - 'download_minidump_stackwalk': True, 02:09:05 INFO - 'download_symbols': 'ondemand', 02:09:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:09:05 INFO - 'tooltool.py': '/tools/tooltool.py', 02:09:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:09:05 INFO - '/tools/misc-python/virtualenv.py')}, 02:09:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:09:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:09:05 INFO - 'gecko_profile': False, 02:09:05 INFO - 'gecko_profile_interval': 0, 02:09:05 INFO - 'installer_path': 'installer.exe', 02:09:05 INFO - 'log_level': 'info', 02:09:05 INFO - 'log_name': 'talos', 02:09:05 INFO - 'log_to_console': True, 02:09:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:09:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:09:05 INFO - 'opt_config_files': (), 02:09:05 INFO - 'pip_index': False, 02:09:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:09:05 INFO - 'cmd': ('bash', 02:09:05 INFO - '-c', 02:09:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:09:05 INFO - 'enabled': True, 02:09:05 INFO - 'halt_on_failure': False, 02:09:05 INFO - 'name': 'check_screen_resolution'},), 02:09:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:09:05 INFO - 'cmd': ('bash', 02:09:05 INFO - '-c', 02:09:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:09:05 INFO - 'enabled': True, 02:09:05 INFO - 'halt_on_failure': False, 02:09:05 INFO - 'name': 'check_screen_resolution'},), 02:09:05 INFO - 'run_cmd_checks_enabled': True, 02:09:05 INFO - 'suite': 'dromaeojs-e10s', 02:09:05 INFO - 'system_bits': '32', 02:09:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:09:05 INFO - 'title': 't-yosemite-r7-0120', 02:09:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:09:05 INFO - 'use_talos_json': True, 02:09:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:09:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:09:05 INFO - 'work_dir': 'build'} 02:09:05 INFO - [mozharness: 2017-05-27 09:09:05.525432Z] Running clobber step. 02:09:05 INFO - Running pre-action listener: _resource_record_pre_action 02:09:05 INFO - Running main action method: clobber 02:09:05 INFO - rmtree: /builds/slave/test/build 02:09:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:09:06 INFO - Running post-action listener: _resource_record_post_action 02:09:06 INFO - [mozharness: 2017-05-27 09:09:06.221603Z] Finished clobber step (success) 02:09:06 INFO - [mozharness: 2017-05-27 09:09:06.221741Z] Running read-buildbot-config step. 02:09:06 INFO - Running pre-action listener: _resource_record_pre_action 02:09:06 INFO - Running main action method: read_buildbot_config 02:09:06 INFO - Using buildbot properties: 02:09:06 INFO - { 02:09:06 INFO - "project": "", 02:09:06 INFO - "product": "firefox", 02:09:06 INFO - "who": "archaeopteryx@coole-files.de", 02:09:06 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 02:09:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:09:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 02:09:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:09:06 INFO - "stage_platform": "macosx64", 02:09:06 INFO - "basedir": "/builds/slave/test", 02:09:06 INFO - "buildnumber": 67, 02:09:06 INFO - "platform": "yosemite_r7", 02:09:06 INFO - "parent_task_id": "NPJQuN3jSrSTsYtFw45bZA", 02:09:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:09:06 INFO - "slavebuilddir": "test", 02:09:06 INFO - "taskId": "EP1NV-w9RXiwDGWPK-zubA", 02:09:06 INFO - "branch": "mozilla-central", 02:09:06 INFO - "script_repo_revision": "production", 02:09:06 INFO - "revision": "1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c", 02:09:06 INFO - "slavename": "t-yosemite-r7-0120", 02:09:06 INFO - "repo_path": "mozilla-central" 02:09:06 INFO - } 02:09:06 INFO - Finding installer, test and symbols from parent task. 02:09:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EP1NV-w9RXiwDGWPK-zubA'}, attempt #1 02:09:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EP1NV-w9RXiwDGWPK-zubA'}, attempt #1 02:09:07 INFO - Task dependencies: Ue7n0dirQXGLao6sbvyUfQ NPJQuN3jSrSTsYtFw45bZA 02:09:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA'}, attempt #1 02:09:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:09:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/test_packages.json 02:09:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/target.crashreporter-symbols.zip 02:09:07 INFO - Running post-action listener: _resource_record_post_action 02:09:07 INFO - [mozharness: 2017-05-27 09:09:07.905123Z] Finished read-buildbot-config step (success) 02:09:07 INFO - [mozharness: 2017-05-27 09:09:07.905245Z] Running download-and-extract step. 02:09:07 INFO - Running pre-action listener: _resource_record_pre_action 02:09:07 INFO - Running main action method: download_and_extract 02:09:07 INFO - mkdir: /builds/slave/test/build/tests 02:09:07 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')]} 02:09:07 INFO - trying https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:09:07 INFO - Downloading https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/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 02:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/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 02:09:08 INFO - Downloaded 1325 bytes. 02:09:08 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:09:08 INFO - Using the following test package requirements: 02:09:08 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:09:08 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:09:08 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:09:08 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:09:08 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'jsshell-mac.zip'], 02:09:08 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:09:08 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:09:08 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:09:08 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:09:08 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:09:08 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:09:08 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:09:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:09:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:09:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:09:08 INFO - Fetch https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:09:10 INFO - Content-Length response header: 57628588 02:09:10 INFO - Bytes received: 57628588 02:09:29 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 02:09:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:09:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:09:29 INFO - Fetch https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:09:32 INFO - Content-Length response header: 57628588 02:09:32 INFO - Bytes received: 57628588 02:09:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:09:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:09:49 INFO - Fetch https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:09:50 INFO - Content-Length response header: 14441221 02:09:50 INFO - Bytes received: 14441221 02:09:50 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')]} 02:09:50 INFO - trying https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:09:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:09:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/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 02:09:52 INFO - Downloaded 67303328 bytes. 02:09:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:09:52 INFO - mkdir: /builds/slave/test/properties 02:09:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:09:52 INFO - Writing to file /builds/slave/test/properties/build_url 02:09:52 INFO - Contents: 02:09:52 INFO - build_url:https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:09:52 INFO - Running post-action listener: _resource_record_post_action 02:09:52 INFO - Running post-action listener: set_extra_try_arguments 02:09:52 INFO - [mozharness: 2017-05-27 09:09:52.894150Z] Finished download-and-extract step (success) 02:09:52 INFO - [mozharness: 2017-05-27 09:09:52.894308Z] Running populate-webroot step. 02:09:52 INFO - Running pre-action listener: _resource_record_pre_action 02:09:52 INFO - Running main action method: populate_webroot 02:09:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:09:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:09:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:09:52 INFO - '--tppagecycles', 02:09:52 INFO - '7'], 02:09:52 INFO - 'tests': ['tsvgm']}}, 02:09:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:09:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:09:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:09:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:09:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['damp', 'tps']}, 02:09:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'tests': ['damp', 'tps']}, 02:09:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['dromaeo_dom']}, 02:09:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:09:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:09:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:09:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:09:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['a11yr', 02:09:52 INFO - 'ts_paint', 02:09:52 INFO - 'tpaint', 02:09:52 INFO - 'sessionrestore', 02:09:52 INFO - 'sessionrestore_no_auto_restore', 02:09:52 INFO - 'tabpaint']}, 02:09:52 INFO - 'other-e10s': {'tests': ['a11yr', 02:09:52 INFO - 'ts_paint', 02:09:52 INFO - 'tpaint', 02:09:52 INFO - 'sessionrestore', 02:09:52 INFO - 'sessionrestore_no_auto_restore', 02:09:52 INFO - 'tabpaint']}, 02:09:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:09:52 INFO - 'ts_paint', 02:09:52 INFO - 'tpaint', 02:09:52 INFO - 'sessionrestore', 02:09:52 INFO - 'sessionrestore_no_auto_restore', 02:09:52 INFO - 'tabpaint']}, 02:09:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:09:52 INFO - 'ts_paint', 02:09:52 INFO - 'tpaint', 02:09:52 INFO - 'sessionrestore', 02:09:52 INFO - 'sessionrestore_no_auto_restore', 02:09:52 INFO - 'tabpaint']}, 02:09:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['a11yr', 02:09:52 INFO - 'ts_paint', 02:09:52 INFO - 'tpaint', 02:09:52 INFO - 'sessionrestore', 02:09:52 INFO - 'sessionrestore_no_auto_restore', 02:09:52 INFO - 'tabpaint']}, 02:09:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['a11yr', 02:09:52 INFO - 'ts_paint', 02:09:52 INFO - 'tpaint', 02:09:52 INFO - 'sessionrestore', 02:09:52 INFO - 'sessionrestore_no_auto_restore', 02:09:52 INFO - 'tabpaint']}, 02:09:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:09:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:09:52 INFO - 'bloom_basic_ref']}, 02:09:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['tsvgx', 02:09:52 INFO - 'tsvgr_opacity', 02:09:52 INFO - 'tart', 02:09:52 INFO - 'tscrollx', 02:09:52 INFO - 'cart', 02:09:52 INFO - 'tsvg_static']}, 02:09:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:09:52 INFO - 'tsvgr_opacity', 02:09:52 INFO - 'tart', 02:09:52 INFO - 'tscrollx', 02:09:52 INFO - 'cart', 02:09:52 INFO - 'tsvg_static']}, 02:09:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'talos_options': ['--disable-e10s'], 02:09:52 INFO - 'tests': ['tp5o']}, 02:09:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:09:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'talos_options': ['--disable-e10s', 02:09:52 INFO - '--xperf_path', 02:09:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:52 INFO - 'tests': ['tp5n']}, 02:09:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:09:52 INFO - 'talos_options': ['--xperf_path', 02:09:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:52 INFO - 'tests': ['tp5n']}}, 02:09:52 INFO - 'talos.zip': {'path': '', 02:09:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:09:52 INFO - Running post-action listener: _resource_record_post_action 02:09:52 INFO - [mozharness: 2017-05-27 09:09:52.915983Z] Finished populate-webroot step (success) 02:09:52 INFO - [mozharness: 2017-05-27 09:09:52.916106Z] Running create-virtualenv step. 02:09:52 INFO - Running pre-action listener: _resource_record_pre_action 02:09:52 INFO - Running main action method: create_virtualenv 02:09:52 INFO - Creating virtualenv /builds/slave/test/build/venv 02:09:52 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 02:09:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:09:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:09:53 INFO - Using real prefix '/tools/python27' 02:09:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:09:53 INFO - Installing distribute.............................................................................................................................................................................................done. 02:09:56 INFO - Installing pip.................done. 02:09:56 INFO - Return code: 0 02:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:09:56 INFO - Reading from file tmpfile_stdout 02:09:56 INFO - Output received: 02:09:56 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) 02:09:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:09:56 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')]} 02:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:56 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')]} 02:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:56 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 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:09:56 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 02:09:56 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 02:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:56 INFO - 'HOME': '/Users/cltbld', 02:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:56 INFO - 'LOGNAME': 'cltbld', 02:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:56 INFO - 'MOZ_NO_REMOTE': '1', 02:09:56 INFO - 'NO_EM_RESTART': '1', 02:09:56 INFO - 'PAGER': '/bin/cat', 02:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:56 INFO - 'PWD': '/builds/slave/test', 02:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:56 INFO - 'SHELL': '/bin/bash', 02:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:09:56 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:56 INFO - 'USER': 'cltbld', 02:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:56 INFO - 'XPC_FLAGS': '0x0', 02:09:56 INFO - 'XPC_SERVICE_NAME': '0', 02:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:57 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 02:09:57 INFO - Cleaning up... 02:09:57 INFO - Return code: 0 02:09:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09: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')]} 02:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:58 INFO - 'HOME': '/Users/cltbld', 02:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:58 INFO - 'LOGNAME': 'cltbld', 02:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:58 INFO - 'MOZ_NO_REMOTE': '1', 02:09:58 INFO - 'NO_EM_RESTART': '1', 02:09:58 INFO - 'PAGER': '/bin/cat', 02:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:58 INFO - 'PWD': '/builds/slave/test', 02:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:58 INFO - 'SHELL': '/bin/bash', 02:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:09:58 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:58 INFO - 'USER': 'cltbld', 02:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:58 INFO - 'XPC_FLAGS': '0x0', 02:09:58 INFO - 'XPC_SERVICE_NAME': '0', 02:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:58 INFO - Downloading/unpacking psutil>=3.1.1 02:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:10:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:10:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:10:00 INFO - Installing collected packages: psutil 02:10:00 INFO - Running setup.py install for psutil 02:10:00 INFO - building 'psutil._psutil_osx' extension 02:10:00 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 02:10:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:10:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:10:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:10:01 INFO - building 'psutil._psutil_posix' extension 02:10:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:10:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:10:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:10:01 INFO - ^ 02:10:01 INFO - 1 warning generated. 02:10:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:10:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:10:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:10:01 INFO - Successfully installed psutil 02:10:01 INFO - Cleaning up... 02:10:01 INFO - Return code: 0 02:10:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10: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')]} 02:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:01 INFO - 'HOME': '/Users/cltbld', 02:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:01 INFO - 'LOGNAME': 'cltbld', 02:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:01 INFO - 'MOZ_NO_REMOTE': '1', 02:10:01 INFO - 'NO_EM_RESTART': '1', 02:10:01 INFO - 'PAGER': '/bin/cat', 02:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:01 INFO - 'PWD': '/builds/slave/test', 02:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:01 INFO - 'SHELL': '/bin/bash', 02:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:01 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:01 INFO - 'USER': 'cltbld', 02:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:01 INFO - 'XPC_FLAGS': '0x0', 02:10:01 INFO - 'XPC_SERVICE_NAME': '0', 02:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:10:03 INFO - Installing collected packages: mozsystemmonitor 02:10:03 INFO - Running setup.py install for mozsystemmonitor 02:10:03 INFO - Successfully installed mozsystemmonitor 02:10:03 INFO - Cleaning up... 02:10:03 INFO - Return code: 0 02:10:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:10:03 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')]} 02:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:03 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')]} 02:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:03 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 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:03 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 02:10:03 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 02:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:03 INFO - 'HOME': '/Users/cltbld', 02:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:03 INFO - 'LOGNAME': 'cltbld', 02:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:03 INFO - 'MOZ_NO_REMOTE': '1', 02:10:03 INFO - 'NO_EM_RESTART': '1', 02:10:03 INFO - 'PAGER': '/bin/cat', 02:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:03 INFO - 'PWD': '/builds/slave/test', 02:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:03 INFO - 'SHELL': '/bin/bash', 02:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:03 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:03 INFO - 'USER': 'cltbld', 02:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:03 INFO - 'XPC_FLAGS': '0x0', 02:10:03 INFO - 'XPC_SERVICE_NAME': '0', 02:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:04 INFO - Downloading/unpacking jsonschema==2.5.1 02:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:10:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:05 INFO - Downloading functools32-3.2.3-2.tar.gz 02:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:10:05 INFO - warning: no files found matching '*.txt' 02:10:05 INFO - no previously-included directories found matching 'build' 02:10:05 INFO - no previously-included directories found matching 'dist' 02:10:05 INFO - no previously-included directories found matching '.git*' 02:10:05 INFO - Installing collected packages: jsonschema, functools32 02:10:05 INFO - Running setup.py install for functools32 02:10:06 INFO - warning: no files found matching '*.txt' 02:10:06 INFO - no previously-included directories found matching 'build' 02:10:06 INFO - no previously-included directories found matching 'dist' 02:10:06 INFO - no previously-included directories found matching '.git*' 02:10:06 INFO - Successfully installed jsonschema functools32 02:10:06 INFO - Cleaning up... 02:10:06 INFO - Return code: 0 02:10:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:06 INFO - 'HOME': '/Users/cltbld', 02:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:06 INFO - 'LOGNAME': 'cltbld', 02:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:06 INFO - 'MOZ_NO_REMOTE': '1', 02:10:06 INFO - 'NO_EM_RESTART': '1', 02:10:06 INFO - 'PAGER': '/bin/cat', 02:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:06 INFO - 'PWD': '/builds/slave/test', 02:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:06 INFO - 'SHELL': '/bin/bash', 02:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:06 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:06 INFO - 'USER': 'cltbld', 02:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:06 INFO - 'XPC_FLAGS': '0x0', 02:10:06 INFO - 'XPC_SERVICE_NAME': '0', 02:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:10:06 INFO - Cleaning up... 02:10:06 INFO - Return code: 0 02:10:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:06 INFO - 'HOME': '/Users/cltbld', 02:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:06 INFO - 'LOGNAME': 'cltbld', 02:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:06 INFO - 'MOZ_NO_REMOTE': '1', 02:10:06 INFO - 'NO_EM_RESTART': '1', 02:10:06 INFO - 'PAGER': '/bin/cat', 02:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:06 INFO - 'PWD': '/builds/slave/test', 02:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:06 INFO - 'SHELL': '/bin/bash', 02:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:06 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:06 INFO - 'USER': 'cltbld', 02:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:06 INFO - 'XPC_FLAGS': '0x0', 02:10:06 INFO - 'XPC_SERVICE_NAME': '0', 02:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:06 INFO - Downloading/unpacking blobuploader==1.2.4 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:10:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:10:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:08 INFO - Downloading docopt-0.6.1.tar.gz 02:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:10:09 INFO - Installing collected packages: blobuploader, requests, docopt 02:10:09 INFO - Running setup.py install for blobuploader 02:10:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:10:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:10:09 INFO - Running setup.py install for requests 02:10:09 INFO - Running setup.py install for docopt 02:10:09 INFO - Successfully installed blobuploader requests docopt 02:10:09 INFO - Cleaning up... 02:10:09 INFO - Return code: 0 02:10:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10: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')]} 02:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:09 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 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:09 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 02:10:09 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 02:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:09 INFO - 'HOME': '/Users/cltbld', 02:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:09 INFO - 'LOGNAME': 'cltbld', 02:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:09 INFO - 'MOZ_NO_REMOTE': '1', 02:10:09 INFO - 'NO_EM_RESTART': '1', 02:10:09 INFO - 'PAGER': '/bin/cat', 02:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:09 INFO - 'PWD': '/builds/slave/test', 02:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:09 INFO - 'SHELL': '/bin/bash', 02:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:09 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:09 INFO - 'USER': 'cltbld', 02:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:09 INFO - 'XPC_FLAGS': '0x0', 02:10:09 INFO - 'XPC_SERVICE_NAME': '0', 02:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-fxK18X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-CTs1h6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-mscOld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-36xkZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-znfvPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-3KEBVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-RTuqgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Z_1I62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:10:10 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-QEPwaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Lenwem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-QtKz0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-HbCNqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-iy155B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-2idb1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vrs3uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-LbsULX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:10:11 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-oEzeqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:10:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:10:11 INFO - Running setup.py install for manifestparser 02:10:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozcrash 02:10:12 INFO - Running setup.py install for mozdebug 02:10:12 INFO - Running setup.py install for mozdevice 02:10:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozfile 02:10:12 INFO - Running setup.py install for mozhttpd 02:10:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozinfo 02:10:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozInstall 02:10:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Running setup.py install for mozleak 02:10:13 INFO - Running setup.py install for mozlog 02:10:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Running setup.py install for moznetwork 02:10:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Running setup.py install for mozprocess 02:10:13 INFO - Running setup.py install for mozprofile 02:10:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Running setup.py install for mozrunner 02:10:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:10:14 INFO - Running setup.py install for mozscreenshot 02:10:14 INFO - Running setup.py install for moztest 02:10:14 INFO - Running setup.py install for mozversion 02:10:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:10:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:10:14 INFO - Cleaning up... 02:10:14 INFO - Return code: 0 02:10:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:14 INFO - 'HOME': '/Users/cltbld', 02:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:14 INFO - 'LOGNAME': 'cltbld', 02:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:14 INFO - 'MOZ_NO_REMOTE': '1', 02:10:14 INFO - 'NO_EM_RESTART': '1', 02:10:14 INFO - 'PAGER': '/bin/cat', 02:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:14 INFO - 'PWD': '/builds/slave/test', 02:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:14 INFO - 'SHELL': '/bin/bash', 02:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:14 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:14 INFO - 'USER': 'cltbld', 02:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:14 INFO - 'XPC_FLAGS': '0x0', 02:10:14 INFO - 'XPC_SERVICE_NAME': '0', 02:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:10:14 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-7dLgML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:10:14 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)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:10:14 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-OhBMXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-f0emCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-k54lMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-P4Eegi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-nOfGG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-xg1Lbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-5pq82d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-3N_XXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-evCWTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:10:15 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)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-NrbtQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:10:15 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)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:10:15 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-rPyXME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:10:16 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-obOSf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:10:16 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Elqp7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:16 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-3tavEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:10:16 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-3jb6RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:10:16 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-X4Y_9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - Downloading blessings-1.6.tar.gz 02:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:10:18 INFO - Installing collected packages: blessings 02:10:18 INFO - Running setup.py install for blessings 02:10:18 INFO - Successfully installed blessings 02:10:18 INFO - Cleaning up... 02:10:18 INFO - Return code: 0 02:10:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:10:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:18 INFO - Reading from file tmpfile_stdout 02:10:18 INFO - Current package versions: 02:10:18 INFO - blessings == 1.6 02:10:18 INFO - blobuploader == 1.2.4 02:10:18 INFO - docopt == 0.6.1 02:10:18 INFO - functools32 == 3.2.3-2 02:10:18 INFO - jsonschema == 2.5.1 02:10:18 INFO - manifestparser == 1.1 02:10:18 INFO - mozInstall == 1.12 02:10:18 INFO - mozcrash == 1.0 02:10:18 INFO - mozdebug == 0.1 02:10:18 INFO - mozdevice == 0.51 02:10:18 INFO - mozfile == 1.2 02:10:18 INFO - mozhttpd == 0.7 02:10:18 INFO - mozinfo == 0.9 02:10:18 INFO - mozleak == 0.1 02:10:18 INFO - mozlog == 3.4 02:10:18 INFO - moznetwork == 0.27 02:10:18 INFO - mozprocess == 0.25 02:10:18 INFO - mozprofile == 0.28 02:10:18 INFO - mozrunner == 6.13 02:10:18 INFO - mozscreenshot == 0.1 02:10:18 INFO - mozsystemmonitor == 0.3 02:10:18 INFO - moztest == 0.8 02:10:18 INFO - mozversion == 1.4 02:10:18 INFO - psutil == 3.1.1 02:10:18 INFO - requests == 1.2.3 02:10:18 INFO - wsgiref == 0.1.2 02:10:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:18 INFO - 'HOME': '/Users/cltbld', 02:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:18 INFO - 'LOGNAME': 'cltbld', 02:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:18 INFO - 'MOZ_NO_REMOTE': '1', 02:10:18 INFO - 'NO_EM_RESTART': '1', 02:10:18 INFO - 'PAGER': '/bin/cat', 02:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:18 INFO - 'PWD': '/builds/slave/test', 02:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:18 INFO - 'SHELL': '/bin/bash', 02:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:18 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:18 INFO - 'USER': 'cltbld', 02:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:18 INFO - 'XPC_FLAGS': '0x0', 02:10:18 INFO - 'XPC_SERVICE_NAME': '0', 02:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 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)) 02:10:18 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:20 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:10:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:10:20 INFO - Installing collected packages: marionette-driver 02:10:20 INFO - Running setup.py install for marionette-driver 02:10:20 INFO - Successfully installed marionette-driver 02:10:20 INFO - Cleaning up... 02:10:20 INFO - Return code: 0 02:10:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aa04d4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, '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.84jE1ey9ki/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.nx2Fti5C6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:20 INFO - 'HOME': '/Users/cltbld', 02:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:20 INFO - 'LOGNAME': 'cltbld', 02:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:20 INFO - 'MOZ_NO_REMOTE': '1', 02:10:20 INFO - 'NO_EM_RESTART': '1', 02:10:20 INFO - 'PAGER': '/bin/cat', 02:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:20 INFO - 'PWD': '/builds/slave/test', 02:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:20 INFO - 'SHELL': '/bin/bash', 02:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:20 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:20 INFO - 'USER': 'cltbld', 02:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:20 INFO - 'XPC_FLAGS': '0x0', 02:10:20 INFO - 'XPC_SERVICE_NAME': '0', 02:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:10:20 INFO - Cleaning up... 02:10:20 INFO - Return code: 0 02:10:20 INFO - Running post-action listener: _resource_record_post_action 02:10:20 INFO - Running post-action listener: _start_resource_monitoring 02:10:20 INFO - Starting resource monitoring. 02:10:20 INFO - [mozharness: 2017-05-27 09:10:20.958326Z] Finished create-virtualenv step (success) 02:10:20 INFO - [mozharness: 2017-05-27 09:10:20.959333Z] Running install step. 02:10:20 INFO - Running pre-action listener: _resource_record_pre_action 02:10:20 INFO - Running main action method: install 02:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:21 INFO - Reading from file tmpfile_stdout 02:10:21 INFO - Detecting whether we're running mozinstall >=1.0... 02:10:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:10:21 INFO - Reading from file tmpfile_stdout 02:10:21 INFO - Output received: 02:10:21 INFO - Usage: mozinstall [options] installer 02:10:21 INFO - Options: 02:10:21 INFO - -h, --help show this help message and exit 02:10:21 INFO - -d DEST, --destination=DEST 02:10:21 INFO - Directory to install application into. [default: 02:10:21 INFO - "/builds/slave/test"] 02:10:21 INFO - --app=APP Application being installed. [default: firefox] 02:10:21 INFO - mkdir: /builds/slave/test/build/application 02:10:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:10:42 INFO - Reading from file tmpfile_stdout 02:10:42 INFO - Output received: 02:10:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:10:42 INFO - Running post-action listener: _resource_record_post_action 02:10:42 INFO - [mozharness: 2017-05-27 09:10:42.734560Z] Finished install step (success) 02:10:42 INFO - [mozharness: 2017-05-27 09:10:42.734695Z] Running run-tests step. 02:10:42 INFO - Running pre-action listener: _resource_record_pre_action 02:10:42 INFO - Running main action method: run_tests 02:10:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:10:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:10:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:10:42 INFO - 2017-05-27 02:10:42.749 screenresolution[6766:123122] starting screenresolution argv=screenresolution get 02:10:42 INFO - 2017-05-27 02:10:42.752 screenresolution[6766:123122] Display 0: 1600x1200x32@60 02:10:42 INFO - 2017-05-27 02:10:42.758 screenresolution[6767:123124] starting screenresolution argv=screenresolution list 02:10:42 INFO - Available Modes on Display 0 02:10:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:10:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:10:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:10:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:10:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:10:42 INFO - Intel Iris: 02:10:42 INFO - Chipset Model: Intel Iris 02:10:42 INFO - Type: GPU 02:10:42 INFO - Bus: Built-In 02:10:42 INFO - VRAM (Dynamic, Max): 1536 MB 02:10:42 INFO - Vendor: Intel (0x8086) 02:10:42 INFO - Device ID: 0x0a2e 02:10:42 INFO - Revision ID: 0x0009 02:10:42 INFO - Displays: 02:10:42 INFO - Display: 02:10:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:10:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:10:42 INFO - Display Serial Number: 1600x1200 60 02:10:42 INFO - Main Display: Yes 02:10:42 INFO - Mirror: Off 02:10:42 INFO - Online: Yes 02:10:42 INFO - Rotation: Supported 02:10:42 INFO - Return code: 0 02:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:10:42 INFO - Python 2.7.3 02:10:42 INFO - Return code: 0 02:10:42 INFO - grabbing minidump binary from tooltool 02:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10250ad30>, '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 0x1024bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:10:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:10:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:10:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:10:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:10:43 INFO - Return code: 0 02:10:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:10:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:10:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:10:43 INFO - ENV: RUST_BACKTRACE is now 1 02:10:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:10:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 02:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0120 --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 02:10:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84jE1ey9ki/Render', 02:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:43 INFO - 'HOME': '/Users/cltbld', 02:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:43 INFO - 'LOGNAME': 'cltbld', 02:10:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:10:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:43 INFO - 'MOZ_NO_REMOTE': '1', 02:10:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:10:43 INFO - 'NO_EM_RESTART': '1', 02:10:43 INFO - 'PAGER': '/bin/cat', 02:10:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:43 INFO - 'PWD': '/builds/slave/test', 02:10:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:43 INFO - 'RUST_BACKTRACE': '1', 02:10:43 INFO - 'SHELL': '/bin/bash', 02:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx2Fti5C6o/Listeners', 02:10:43 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:43 INFO - 'USER': 'cltbld', 02:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:43 INFO - 'XPC_FLAGS': '0x0', 02:10:43 INFO - 'XPC_SERVICE_NAME': '0', 02:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 02:10:43 INFO - mozversion application_buildid: 20170527005441 02:10:43 INFO - mozversion application_changeset: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c 02:10:43 INFO - mozversion application_display_name: Nightly 02:10:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:10:43 INFO - mozversion application_name: Firefox 02:10:43 INFO - mozversion application_remotingname: firefox 02:10:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:10:43 INFO - mozversion application_vendor: Mozilla 02:10:43 INFO - mozversion application_version: 55.0a1 02:10:43 INFO - mozversion platform_buildid: 20170527005441 02:10:43 INFO - mozversion platform_changeset: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c 02:10:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:10:43 INFO - mozversion platform_version: 55.0a1 02:10:43 INFO - using testdate: 1495876243 02:10:43 INFO - actual date: 1495876243 02:10:43 INFO - starting webserver on 'localhost:57800' 02:10:43 INFO - SUITE-START | Running 2 tests 02:10:43 INFO - TEST-START | dromaeo_css 02:10:43 INFO - operating with platform_type : mac_ 02:10:43 INFO - Initialising browser for dromaeo_css test... 02:10:43 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57800/getInfo.html -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpQGnwrl/profile 02:10:43 INFO - TEST-INFO | started process 6777 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57800/getInfo.html -foreground) 02:11:16 INFO - TEST-INFO | 6777: killed by SIGTERM 02:11:16 INFO - Browser initialized. 02:11:16 INFO - Running cycle 1/1 for dromaeo_css test... 02:11:16 INFO - TEST-INFO | started process 6784 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpQGnwrl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:11:18 INFO - PID 6784 | 2017-05-27 02:11:18.825 plugin-container[6785:123595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 02:11:18 INFO - PID 6784 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:11:18 INFO - PID 6784 | 2017-05-27 02:11:18.826 plugin-container[6785:123595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 02:11:18 INFO - PID 6784 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:11:18 INFO - PID 6784 | 2017-05-27 02:11:18.826 plugin-container[6785:123595] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:11:19 INFO - PID 6784 | 2017-05-27 02:11:19.540 plugin-container[6786:123637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 02:11:19 INFO - PID 6784 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:11:19 INFO - PID 6784 | 2017-05-27 02:11:19.541 plugin-container[6786:123637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 02:11:19 INFO - PID 6784 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:11:19 INFO - PID 6784 | 2017-05-27 02:11:19.541 plugin-container[6786:123637] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:11:28 INFO - PID 6784 | RSS: Main: 242565120 02:11:28 INFO - PID 6784 | 02:14:31 INFO - PID 6784 | Cycle 1(1): loaded http://localhost:57800/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57800/tests/dromaeo/cssquery-ext.html) 02:14:32 INFO - PID 6784 | RSS: Main: 255655936 02:14:32 INFO - PID 6784 | 02:17:34 INFO - PID 6784 | Cycle 1(1): loaded http://localhost:57800/tests/dromaeo/cssquery-ext.html (next: http://localhost:57800/tests/dromaeo/cssquery-jquery.html) 02:17:35 INFO - PID 6784 | RSS: Main: 270196736 02:17:35 INFO - PID 6784 | 02:20:36 INFO - PID 6784 | Cycle 1(1): loaded http://localhost:57800/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57800/tests/dromaeo/cssquery-mootools.html) 02:20:37 INFO - PID 6784 | RSS: Main: 270651392 02:20:37 INFO - PID 6784 | 02:23:39 INFO - PID 6784 | Cycle 1(1): loaded http://localhost:57800/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57800/tests/dromaeo/cssquery-prototype.html) 02:23:39 INFO - PID 6784 | RSS: Main: 274554880 02:23:39 INFO - PID 6784 | 02:26:42 INFO - PID 6784 | Cycle 1(1): loaded http://localhost:57800/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57800/tests/dromaeo/cssquery-yui.html) 02:26:43 INFO - PID 6784 | RSS: Main: 274751488 02:26:43 INFO - PID 6784 | 02:29:44 INFO - PID 6784 | Cycle 1(1): loaded http://localhost:57800/tests/dromaeo/cssquery-yui.html (next: http://localhost:57800/tests/dromaeo/cssquery-dojo.html) 02:29:45 INFO - PID 6784 | RSS: Main: 276250624 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | __start_tp_report 02:29:45 INFO - PID 6784 | _x_x_mozilla_page_load 02:29:45 INFO - PID 6784 | _x_x_mozilla_page_load_details 02:29:45 INFO - PID 6784 | |i|pagename|runs| 02:29:45 INFO - PID 6784 | |0;dojo.html;3556.679898809107;3561.483584880192;3567.6432356764312;3584.1039740064984;3598.9100272493183;6184.56708030438;6215.8446038849;6276.529260305484;6284.277308109568;6300.684965751711;6192.845178870531;6215.316315205324;6221.688915554227;6224.502039836813;6284.340144284842;5401.567874570044;5495.862603434926;5499.945000549983;5505.779768809243;5530.170474428827;5557.944420555803;5615.073512870372;5623.578231632644;5664.0937450007805;5678.26182596264;5414.242006119146;5528.308961379827;5559.555235581144;5609.80365687201;5628.831135065954;5462.617616766828;5501.752421141058;5513.448655134494;5556.333240011184;5556.583256255756;5466.726663666801;5478.671279723229;5484.643498172646;5500.917486237709;5522.392536820947;5104.948950510485;6299.401556852092;6305.621662700207;6306.590071645328;6328.303886572473;6499.122618446497;6583.604983700993;6631.966840165816;6748.4938629602975;6775.457963362919;6878.58728476293;6898.206706228798;6922.203946546161;6922.58464492132;6922.930770692279;6350.555461117724;6378.681065946684;6381.234251889757;6389.041643753474;6392.009238568029;6745.291744366771;6858.177018757781;6885.277045910208;6992.405645520188;7021.122359705037;6316.526260530387;6482.416582507597;6606.6036037837885;6669.499787515956;6697.196336439561;5724.398938111522;6770.086038384854;6862.4853136015;6874.725010999504;6877.277885822016;6701.195856497154;6714.731410743613;6719.899201511981;6725.19297684281;6753.3246675332075;6477.546571739933;6734.191896972395;6757;6759.357861003196;6759.898601520981;5347.0375332440535;5356.303680521507;5377.8117765878005;5407.702576358338;5441.891162176735;4791.305260737243;4797.544233297831;4800.73595952226;4845.200541910545;4850.466448690641;4744.620430365631;6198.752049917953;6334.588251763621;6335.176427064452;6338.366163383625;6579.342065793382;6680.96659516709;6749.5612785168805;6834.931650683429;6893.138357705287;6283.560150789401;6321.96839015802;6333.1450254216015;6338.841528961812;6347.365263473708;4668.836590719308;6005.549583781234;6050.9394906050375;6064;6073.240844894388;6768.763093291711;6890.655467226518;6932.202796678446;6978.895316570255;7038.824029399306;6757.695903684445;6809.863802724017;6846;6846.863062738817;6857.7256909723055;6914.55055421396;6998.265182156004;7021.122359705037;7027.367536921702;7052.0832291801735;6472.870542589216;6565.638889861103;6714.892042812881;6754.054432379569;6767.086443330088;6726.529142960141;6801.693923773343;6849;6875.656217189021;6883.070785444102;6635.601863888182;6647.102641143384;6664.400203981665;6666.899996499959;6675.4325882300545;6321.778737744157;6478.060681201294;6490.156279683611;6595.043718660863;6627.768028118992;6419.358064193543;6494.415502604788;6533.640649764308;6537.1501704778075;6541.6075035498025;6642;6678.3989440950545;6678.632675202911;6692.933070669231;6698.56459330142;6304.117423560797;6366.076918846834;6391.041343798467;6467.256265529524;6488.318726533646 02:29:45 INFO - PID 6784 | |1;ext.html;798.9480683755555;833.1252185205534;850.561960590296;1262.6843289177705;1321.3063141850532;38479;38959.02602434933;39000.60999390012;39037.41443878344;39161.02097447565;32406;33750.49374259382;36761.63238367616;36919;39241.01897452565;7825.295723384894;8052.194780521931;8255.298299644537;8268.627911743986;8269.710560130397;8264.917350826505;8376.49741015538;8384.455010424303;8394.496330220176;8400.621972011244;8337.541435221061;8374.665013399457;8387.706430274942;8425.1153628869;8426.199511046441;7276.417886569062;8165.550894700789;8362.289205417517;8375.49747015175;8405.621747021369;7934.404919630993;8060.314873235782;8083.838323233561;8146;8146.3890208234025;36318.81840590807;36356.63643363585;36374.90875273746;36450.635493645255;36541;3636.563612366533;3670.504481894963;3672.944905826415;3675.099600597844;3679.3193259247123;29885.40229195404;30111.247218819706;30135.547966780516;30233;30264;3035.4991426414617;3096.179512429208;3102.953455698166;3112.1597168764533;3124.7343975762087;201437;213478;213648;255823;260263;45989.5401045992;46035.53964460312;46056.8485787858;46397.76801115973;46424.53575464202;48504.5149548507;48544.786380340774;48573.51426485761;48592;48606.51393486091;14073.2963351832;14201.147931123958;14211.36048877803;14213.573592792232;14225.430982760781;11466;11599.362035087983;11606.129540284512;11617.361045142598;11629.709257268636;10791.730206744895;13221.801672974721;13231.603051908458;13277.336133193496;13405.932970335087;6092.360302168208;6117.694115294217;6156;6158.753649853956;6160.44555989963;927.8680010387317;994.6866736977829;1008.7730260691433;1027.6095083868083;1059.8940105989493;23856.16503422372;24577.754222457905;24588.87705561461;24606;24721.752782472304;1122.5846436818267;1123.550579768083;1135.7387800805861;1152.1877076660935;1178.7701398227434;12457;12493.12548121641;12575.119741617998;12622.495100195889;12648.494060237488;279725;279899;280167;280807;282824;52054.95890082177;52107.47892521102;52141.95716085657;52329.73835130876;52495.73752131215;1484.747592909208;1515.5377609828888;1549.449945269413;1552.1385630974692;1561.6642421879124;23719.407014824767;24003.639945400482;24021.399465013514;24031.399215019763;24156.87921560381;36525.45211821878;36536.81731591325;36661.45007824938;36677.63322366742;36720.449193261586;214123;214777;215557;215919;216631;32711.836440817642;32864.835675821465;33489.665103348656;34198;34379.82810085933;42734.786326069414;44514.55485445104;44879.775601121786;44890.1021979552;44897;28878.24582804222;29545.409091818474;30419.847900760353;31160.065198044096;31254.374912501167;30177;30707.692923070485;30725.53911691371;30825.383492330475;31419.05742827719;30209;35773.64226357703;36019.45970810492;36621;37032.44451333286;29792.851035744683;30330.39339213159;31033.069007928894;31280.843595781877;31297.061088167393;19182;23904.282871513882;24153.15463958753;24397.02411903575;24895.502089957736 02:29:45 INFO - PID 6784 | |2;jquery.html;363.3405369254801;396.8234135809564;406.83036270721675;436.8881197352737;450.2301065178545;19343.613127737437;21058.89470552647;21748.782512174872;21992.89003554982;22141.557168856612;22640.20759273426;23028.65457018146;23705.64441533374;23713.695746733923;23937.521249575;5989.970050149754;6209.285932117797;6240.469560087398;6242.313345531988;6247.219097612799;5867.178594996704;6115.571909966304;6118.816435506942;6199.163112979735;6212.409821066992;5242.501962313574;6019.7893073742425;6079.726412311456;6089.482393996515;6111.113888486167;4967.7764500597195;6010.489108425767;6065.302490213616;6101.908471372933;6107.877842443148;5377.892442151135;5382.381026181961;5439.129739241742;5439.592030597721;5441.428649991773;27359.45281094367;27505.86247068752;27671.30821729453;27724.445511089867;27820.443591128267;2130.6697461893277;2212.856164349312;2224.566209589115;2228.7882651148116;2229.108356657334;16138.596535086599;17414.651706965917;18137;18277.908610456994;18433;1941.0197850085758;2130.2970019893396;2136.2629892686996;2153.0203757290396;2191.583599116163;139864;149719;152073;153864;157147;12459.875401245872;12730.490780368867;12850.807237891438;12917.612471625866;12962.935185324013;16688.6662266754;16818.24317905697;16824.495265142064;16827.326906923587;16884.662306753795;8956.955215223883;9016.8647470288;9111;9191.540422978824;9286.257099432029;8639.481631102153;8703.216710496086;8722.95638521816;8747.606357713918;8839.867401988975;6858.588484690935;8069.677212911535;8187.263146316741;8199.672013119527;8212.301954333829;2185.661222510513;2187.387531491185;2188.529466164782;2196.330119313625;2358.0803486640225;893.9642414303355;919.6045700348914;961.6874515782399;1001.6677818501375;1008.3344992305043;20484;21184.788152118283;21374.572508549743;21482.462938426354;21521.354359369245;894.3337213775778;957.9521023948774;989.8319982420669;1022.1669339488293;1026.1277913773383;9496.670466134608;10603.310784798963;10745.570177192825;10760.677179684622;11059.059979901798;367373;382714;449010;453578;457814;113856.0451152617;116355;117948.23077653842;118076;119042;1080.3247970018738;1138.1407037686658;1159.3971135009917;1167.1596450555587;1201.1291813435364;22478.662820058038;22748.09007639676;22919.42701432411;22980.655290170995;23137.537249255256;55634.721826392226;56410.43589564216;56557.30328090164;56998.7150064247;57206.41903714476;57050.85898281928;72376;75526.62236688781;76200;76338.61830690812;8894.68868589622;9009;9017.143371379709;9160.496172710566;9190.816183676423;13516.459341626225;13585.79621305701;14027.719445611236;14082.507112251022;14233.430662773788;11705.483321086007;11727.179097463095;11831.053515718893;11975.940120299343;12040.21738586989;10760;10787.892121079003;10949.726256843642;10963.725906852074;11047.668569942914;11779.352135632622;11844.526218951145;11943.343116128697;11983.64049078529;12043.337616431181;7389.593572353572;8650.394472386872;8824.11758824138;8974.429474841745;9000.009998899985;10088.69733907984;11327.15046371509;11403.429828508375;12051.096167787276;12319.076069294659 02:29:45 INFO - PID 6784 | |3;mootools.html;1236.7279198576314;1455.4032846532923;1478.8225412950449;1489.9404023839047;1505.3602219056897;9384.296177786668;11797;11824.290542567453;11844.34856082915;11966.461509232084;11754.471048802803;11882.168248222628;11955.581554645589;11983.161178717493;11991.280523168616;2528.051980507305;2565.6279839423273;2575.35616095976;2575.484903019394;2577.90977315794;2505.5615267328235;2519.143491212987;2537.784288335494;2539.4540173862615;2545.5545279576095;2495.500899820034;2505.711843138035;2518.131244720578;2528.8609126498127;2602.3624212067975;2455.693038370204;2483.4908843687;2489.651448797161;2504.499100179962;2507.5987841945384;2449.3141920262356;2497.0386401235655;2505.7619526144986;2512.0830896722746;2552.5660637691635;11553.480093395818;11658.358790266531;11669.708257293636;11670.35813030283;11680.824787628193;6484.740610375579;6652.833679158011;6716.496262780262;6732.091167692348;6759.121314229168;3583.94624080639;3672.412414013745;4073.348264277731;4228.217779710763;4298.247806633826;1237.8824011718962;1241.7081985733346;1245.4395522015038;1254.316397563321;1258.937053147339;15270.236488175658;15537.145456999973;16169.514914552583;16173.110478923654;16355.59111022222;4489.2592722201125;5469.945300547023;5669.788782374877;5682.6306290091015;5698.059820129715;11620.418979051014;11653.708657283467;11661.416929153509;11663.941680291544;11675.941620291844;6352.555321127569;6391.296957334689;6453.548251622436;6455.838604034937;6480.967595162088;6011.939880601226;6127.846803829851;6130.877382452326;6160.969195154085;6215.564910456315;4311.417958575552;4352.542982986741;4440.666949978699;4481.910361792811;4484.148011877733;1245.5578269714301;1246.6634008817568;1265.6709255593612;1279.6864768131682;1280.3406245783428;791.6200223892564;792.0930534537922;816.1389733830747;820.6963423533331;841.7432683031734;6243.063540468966;6270.717817698214;6466.288708242089;6511.674416279167;6550.737970481127;579.7796837201921;602.6233603997501;604.4137186928672;604.4831668923096;616.8334549671564;3971.62269584389;4001.279769641434;4062.532808727034;4336.284513055373;4577.725336479822;14022.719545609234;14560.708785824225;14585.781213281809;14678.192699401421;14728.263586820616;11201.719957000978;14548.490802822062;14593.85406145925;14611.634709132357;15114.017588856688;8327.625256863455;8359.996800383993;8403.033651130076;8458.703945361884;8502.107278735768;5968.522518198621;6323.988161894074;6816.147981502312;7125.82185445368;7148.320909513588;10587.682369528926;13369.465221391425;13513.054086214264;13651.931740341235;13660.316984150952;10856.294340867818;13351.265680387673;13429.529966451519;13911.513097041556;13930.303484825921;7469.925300747141;9318.627254909728;9373.953130234306;9410.764730881783;9413.529323533932;10562.683119506428;10586.10018148466;10638.787224255626;10644.254902156774;10657.040866322068;8827.426217295855;9141.954290228507;9176.26589872822;9206;9237.769055773659;8933.642654294015;8953.910460895568;9017.90982090197;9039.096090390909;9108.407953483267;8069.556174410229;8291.58542072882;8347.373946954123;8435.536045517556;8472.237498625153;6066.908996365147;6153.600015998945;6173.259208894961;6282.905756413749;6296.338884417253;5486.61593688438;5833.708314584339;6270.655113968775;6320.968395157995;6362.363763623601 02:29:45 INFO - PID 6784 | |4;prototype.html;329.72138542931225;369.6950016236606;387.4749714137344;389.7271909663236;399.0263756434302;5042.344495215617;6065.332813390523;6287.119803227546;6300.275468321144;6359.6184228946295;5232.424433312332;5447.665321996116;5461.853010867722;5653;6169.629822210671;3746.838480071173;4425.579569940851;4478.126765280772;4487.573680500347;4498.482674492426;3760.5863355030924;4373.890652733675;4483.260262056757;4497.775111244425;4522.592966633002;3644.398674218751;4428.446444194476;4436.112777444508;4440.733555986635;4461.709988850731;3672.9164896355505;4175.832966681344;4416.646668266531;4419.359192917009;4428.933565996513;3249.23642943908;3769.738157105742;3837.3092843288196;3837.520309961255;3840.9245411284883;5363.034653762321;5849.824505264849;6003.699815009232;6007.88854062;6067.362926892701;4607.792649330788;5252.923150754086;5386.569074474032;5390.1375779875425;5423.589866634703;6322.209723784527;6594.53838231324;6594.934050659528;6673.366030227169;6695.163104611924;3638.909027274313;4368.400801523088;4368.89077773055;4377.9781101094295;4388.736675799462;5404.405515393303;6036.8188954331445;6678.232003319632;6997.895031574531;7150.642467876637;4159.667226621863;4276.379924910871;4451.265541185668;4456.774387043524;4490.3040028795585;3983.8605648802154;4290.237836002036;4410.875226817206;4527.3435351873795;4554.863354099382;4570.085982803453;4675.766211689402;4967.130752118364;5286.920696189561;5340.279062326614;5215.687058776485;5843.444872737083;5940.6435613863305;6041.761438905014;6728.259891411941;1163.4473625028045;1359.6736783171968;1514.5914299701383;1520.7338715724704;1524.839891811366;867.2324992381662;934.065934065934;995.4326034160604;996.7707427291764;1004.2217281606813;4960.355153829979;6177.1969643945995;6190.350013248634;6192.845178870474;6204.906926396107;6709.798706038827;6772.458203343819;6788.728450862009;6810.6254156020905;6840.863182736317;5441.863953401196;5739.885202295931;5915.585908986416;6146.293176284785;6183.866566034711;3207.486802111651;3514.806685632315;3590.4255319149333;3590.5152804371596;3607.549056367954;6917.575799979043;6966.895496567556;7021.719131234796;7057.717691292393;7104.7158113675905;6179.84550386235;6796.388325050671;7071.82320441993;7090;7094.361507464249;5362.436944120811;5772.480476757112;5890.028145356054;7075.478772063928;7108.893366599608;5954.910676339945;6792.403785110397;6884.8967265490055;6911;6944.583325000516;6283.088952102011;6573.309802470863;6747.763828265987;6814.795556133324;6820.522563420513;6680.498962577729;6733.494987875831;7118.110236220473;7134.785956421316;7185.568865868065;4030.879073627796;4255.276602977563;4371.672124590732;4417.491988421372;4423.756693381895;6888.862222755617;6906.412954898889;6921.227930777839;6922.169339679271;6948.374646281859;6054.031354983182;6243.469305109116;6528.204743695522;6575.914974029231;6596.406323430914;6647.401733843977;6668.266490685924;6668.966655166693;6681.632510211985;6842.836717757894;5051.924221136759;5342.408931668216;6374.597588530516;6427.260865000398;6553.705083271359;6128.264608247039;6245.5628106032145;6592.505562082959;6654.600723956578;6662.135961142622;5912.526997840248;6283.088952102011;6302.464290535356;6394.296627370893;6486.286508484157 02:29:45 INFO - PID 6784 | |5;yui.html;829.9709510167138;839.1734141870261;843.1526316052366;859.7549698335976;870.6430363550945;16722;17179.312827486887;17219.569510762223;18959;19764.407067787954;18394.080295985215;18560.443186704386;18689;18745.7188142178;18748;984.212629896082;992.5682328187225;1004.8743907011624;1009.7071849163771;1010.8079464901682;984.6159997600905;997.7306127345612;1002.5488530161421;1003.7841863999239;1003.8193125237455;1055.5566662001938;1059.2214722179192;1065.7069305940852;1067.5996501312009;1072.8819829818754;1049.01916707878;1054.3410368519676;1059.9470026498682;1063.9095676867514;1066.4241309692757;772.3396495995898;784.9803754906116;784.9882251766228;786.9926494087578;787.3779714025912;16720.41478548245;16854.410095646715;16860.712161128584;17685;17998.730019049726;17467.825321746746;17535.824641753676;17581.384651537264;17828.3760068397;17939.372121975797;14596;14600.196989165592;14678.412863485448;14705.632359191106;14789;3513.104158439607;3526.5415495985612;3557.964420355789;3584.7490675652716;3600.567931848195;148166;149399;149857.25071374682;150945;151646.9999999989;11510;13257.270850103338;13576;13666.658333541742;13689.589312320646;26301.079462219113;26401.603975940376;26614.935402584066;26682;27143.592846107324;7763.262490063435;7812.140664526897;7847.803804904923;7882.605869706492;7889.960550197212;3382.9323413532084;3781.3193625147187;3911.9804400977814;4000.099977505038;4012.6990475713856;4252.4046633471335;4325.199838029975;4359.06280149763;4363.214621368184;4373.846915357947;923.4690053219425;954.1365064616533;957.2916042128775;961.9422834629945;962.3215632978734;503.01660254202943;611.5657882903099;627.4478458956089;639.8688268904864;655.1875674164097;9913.405195688281;10527.736806579896;11479.36863472517;11510.518163193823;12023.098267629963;546.8386825886373;560.4675558219708;617.3918690090258;628.6982248520736;634.1344065350855;5315.654482458628;5584.022796010682;5627.662340259598;5684.147377893267;5757.424257574293;120544.79455205511;123162;123639.38180309221;123672.38163809123;124600;104768;104932;107599.46200268948;107761;108310.91689083008;77481.22518774853;89623;91729.54135229281;98744.01255987491;99912.50831171028;11983.76032479328;12178.939105304416;12502.43077889654;12551.623451296477;12830.55093071738;44716;45095.872603185184;45163.54836451593;45242;45495.54504454913;85491.57254213688;85953;86691;86807.56596216979;87632.68550971613;16486.917565412095;16556.917215413847;16719.665606688042;16743.33026678919;16859.325626974784;18352.990585517924;18402.631947361246;18485;18652.253909843454;18669.34657287043;16472.917635412225;16585.336586536887;16730.665386692443;16988.235529401452;17087.743683845;16486.67026659484;16681.2493437798;16768.58078548046;16787.580310492336;16865.91567042157;16183.838161618232;16298.67402651964;16437.506874793777;16760.916195418944;16773.32906683719;11173.944130279297;11653.41732913368;11996;12139.210951288138;12140.271583705005;11137.665870023913;11489.770204596029;12352.4441400139;13284.80072798928;13523.72952540924 02:29:45 INFO - PID 6784 | __end_tp_report 02:29:45 INFO - PID 6784 | __start_cc_report 02:29:45 INFO - PID 6784 | _x_x_mozilla_cycle_collect,402 02:29:45 INFO - PID 6784 | __end_cc_report 02:29:45 INFO - PID 6784 | __startTimestamp1495877385141__endTimestamp 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | ------- Summary: start ------- 02:29:45 INFO - PID 6784 | Number of tests: 6 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | [#0] dojo.html Cycles:165 Average:6219.47 Median:6467.26 stddev:748.60 (11.6%) stddev-sans-first:721.16 02:29:45 INFO - PID 6784 | Values: 3556.7 3561.5 3567.6 3584.1 3598.9 6184.6 6215.8 6276.5 6284.3 6300.7 6192.8 6215.3 6221.7 6224.5 6284.3 5401.6 5495.9 5499.9 5505.8 5530.2 5557.9 5615.1 5623.6 5664.1 5678.3 5414.2 5528.3 5559.6 5609.8 5628.8 5462.6 5501.8 5513.4 5556.3 5556.6 5466.7 5478.7 5484.6 5500.9 5522.4 5104.9 6299.4 6305.6 6306.6 6328.3 6499.1 6583.6 6632.0 6748.5 6775.5 6878.6 6898.2 6922.2 6922.6 6922.9 6350.6 6378.7 6381.2 6389.0 6392.0 6745.3 6858.2 6885.3 6992.4 7021.1 6316.5 6482.4 6606.6 6669.5 6697.2 5724.4 6770.1 6862.5 6874.7 6877.3 6701.2 6714.7 6719.9 6725.2 6753.3 6477.5 6734.2 6757.0 6759.4 6759.9 5347.0 5356.3 5377.8 5407.7 5441.9 4791.3 4797.5 4800.7 4845.2 4850.5 4744.6 6198.8 6334.6 6335.2 6338.4 6579.3 6681.0 6749.6 6834.9 6893.1 6283.6 6322.0 6333.1 6338.8 6347.4 4668.8 6005.5 6050.9 6064.0 6073.2 6768.8 6890.7 6932.2 6978.9 7038.8 6757.7 6809.9 6846.0 6846.9 6857.7 6914.6 6998.3 7021.1 7027.4 7052.1 6472.9 6565.6 6714.9 6754.1 6767.1 6726.5 6801.7 6849.0 6875.7 6883.1 6635.6 6647.1 6664.4 6666.9 6675.4 6321.8 6478.1 6490.2 6595.0 6627.8 6419.4 6494.4 6533.6 6537.2 6541.6 6642.0 6678.4 6678.6 6692.9 6698.6 6304.1 6366.1 6391.0 6467.3 6488.3 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | [#1] ext.html Cycles:180 Average:39895.78 Median:31912.53 stddev:63510.93 (199.0%) stddev-sans-first:63621.25 02:29:45 INFO - PID 6784 | Values: 798.9 833.1 850.6 1262.7 1321.3 38479.0 38959.0 39000.6 39037.4 39161.0 32406.0 33750.5 36761.6 36919.0 39241.0 7825.3 8052.2 8255.3 8268.6 8269.7 8264.9 8376.5 8384.5 8394.5 8400.6 8337.5 8374.7 8387.7 8425.1 8426.2 7276.4 8165.6 8362.3 8375.5 8405.6 7934.4 8060.3 8083.8 8146.0 8146.4 36318.8 36356.6 36374.9 36450.6 36541.0 3636.6 3670.5 3672.9 3675.1 3679.3 29885.4 30111.2 30135.5 30233.0 30264.0 3035.5 3096.2 3103.0 3112.2 3124.7 201437.0 213478.0 213648.0 255823.0 260263.0 45989.5 46035.5 46056.8 46397.8 46424.5 48504.5 48544.8 48573.5 48592.0 48606.5 14073.3 14201.1 14211.4 14213.6 14225.4 11466.0 11599.4 11606.1 11617.4 11629.7 10791.7 13221.8 13231.6 13277.3 13405.9 6092.4 6117.7 6156.0 6158.8 6160.4 927.9 994.7 1008.8 1027.6 1059.9 23856.2 24577.8 24588.9 24606.0 24721.8 1122.6 1123.6 1135.7 1152.2 1178.8 12457.0 12493.1 12575.1 12622.5 12648.5 279725.0 279899.0 280167.0 280807.0 282824.0 52055.0 52107.5 52142.0 52329.7 52495.7 1484.7 1515.5 1549.4 1552.1 1561.7 23719.4 24003.6 24021.4 24031.4 24156.9 36525.5 36536.8 36661.5 36677.6 36720.4 214123.0 214777.0 215557.0 215919.0 216631.0 32711.8 32864.8 33489.7 34198.0 34379.8 42734.8 44514.6 44879.8 44890.1 44897.0 28878.2 29545.4 30419.8 31160.1 31254.4 30177.0 30707.7 30725.5 30825.4 31419.1 30209.0 35773.6 36019.5 36621.0 37032.4 29792.9 30330.4 31033.1 31280.8 31297.1 19182.0 23904.3 24153.2 24397.0 24895.5 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | [#2] jquery.html Cycles:180 Average:31521.28 Median:22950.04 stddev:73532.67 (320.4%) stddev-sans-first:73701.74 02:29:45 INFO - PID 6784 | Values: 363.3 396.8 406.8 436.9 450.2 19343.6 21058.9 21748.8 21992.9 22141.6 22640.2 23028.7 23705.6 23713.7 23937.5 5990.0 6209.3 6240.5 6242.3 6247.2 5867.2 6115.6 6118.8 6199.2 6212.4 5242.5 6019.8 6079.7 6089.5 6111.1 4967.8 6010.5 6065.3 6101.9 6107.9 5377.9 5382.4 5439.1 5439.6 5441.4 27359.5 27505.9 27671.3 27724.4 27820.4 2130.7 2212.9 2224.6 2228.8 2229.1 16138.6 17414.7 18137.0 18277.9 18433.0 1941.0 2130.3 2136.3 2153.0 2191.6 139864.0 149719.0 152073.0 153864.0 157147.0 12459.9 12730.5 12850.8 12917.6 12962.9 16688.7 16818.2 16824.5 16827.3 16884.7 8957.0 9016.9 9111.0 9191.5 9286.3 8639.5 8703.2 8723.0 8747.6 8839.9 6858.6 8069.7 8187.3 8199.7 8212.3 2185.7 2187.4 2188.5 2196.3 2358.1 894.0 919.6 961.7 1001.7 1008.3 20484.0 21184.8 21374.6 21482.5 21521.4 894.3 958.0 989.8 1022.2 1026.1 9496.7 10603.3 10745.6 10760.7 11059.1 367373.0 382714.0 449010.0 453578.0 457814.0 113856.0 116355.0 117948.2 118076.0 119042.0 1080.3 1138.1 1159.4 1167.2 1201.1 22478.7 22748.1 22919.4 22980.7 23137.5 55634.7 56410.4 56557.3 56998.7 57206.4 57050.9 72376.0 75526.6 76200.0 76338.6 8894.7 9009.0 9017.1 9160.5 9190.8 13516.5 13585.8 14027.7 14082.5 14233.4 11705.5 11727.2 11831.1 11975.9 12040.2 10760.0 10787.9 10949.7 10963.7 11047.7 11779.4 11844.5 11943.3 11983.6 12043.3 7389.6 8650.4 8824.1 8974.4 9000.0 10088.7 11327.2 11403.4 12051.1 12319.1 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | [#3] mootools.html Cycles:180 Average:7001.37 Median:3822.02 stddev:4379.39 (114.6%) stddev-sans-first:4370.25 02:29:45 INFO - PID 6784 | Values: 1236.7 1455.4 1478.8 1489.9 1505.4 9384.3 11797.0 11824.3 11844.3 11966.5 11754.5 11882.2 11955.6 11983.2 11991.3 2528.1 2565.6 2575.4 2575.5 2577.9 2505.6 2519.1 2537.8 2539.5 2545.6 2495.5 2505.7 2518.1 2528.9 2602.4 2455.7 2483.5 2489.7 2504.5 2507.6 2449.3 2497.0 2505.8 2512.1 2552.6 11553.5 11658.4 11669.7 11670.4 11680.8 6484.7 6652.8 6716.5 6732.1 6759.1 3583.9 3672.4 4073.3 4228.2 4298.2 1237.9 1241.7 1245.4 1254.3 1258.9 15270.2 15537.1 16169.5 16173.1 16355.6 4489.3 5469.9 5669.8 5682.6 5698.1 11620.4 11653.7 11661.4 11663.9 11675.9 6352.6 6391.3 6453.5 6455.8 6481.0 6011.9 6127.8 6130.9 6161.0 6215.6 4311.4 4352.5 4440.7 4481.9 4484.1 1245.6 1246.7 1265.7 1279.7 1280.3 791.6 792.1 816.1 820.7 841.7 6243.1 6270.7 6466.3 6511.7 6550.7 579.8 602.6 604.4 604.5 616.8 3971.6 4001.3 4062.5 4336.3 4577.7 14022.7 14560.7 14585.8 14678.2 14728.3 11201.7 14548.5 14593.9 14611.6 15114.0 8327.6 8360.0 8403.0 8458.7 8502.1 5968.5 6324.0 6816.1 7125.8 7148.3 10587.7 13369.5 13513.1 13651.9 13660.3 10856.3 13351.3 13429.5 13911.5 13930.3 7469.9 9318.6 9374.0 9410.8 9413.5 10562.7 10586.1 10638.8 10644.3 10657.0 8827.4 9142.0 9176.3 9206.0 9237.8 8933.6 8953.9 9017.9 9039.1 9108.4 8069.6 8291.6 8347.4 8435.5 8472.2 6066.9 6153.6 6173.3 6282.9 6296.3 5486.6 5833.7 6270.7 6321.0 6362.4 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | [#4] prototype.html Cycles:180 Average:5242.49 Median:5928.11 stddev:1705.18 (28.8%) stddev-sans-first:1669.62 02:29:45 INFO - PID 6784 | Values: 329.7 369.7 387.5 389.7 399.0 5042.3 6065.3 6287.1 6300.3 6359.6 5232.4 5447.7 5461.9 5653.0 6169.6 3746.8 4425.6 4478.1 4487.6 4498.5 3760.6 4373.9 4483.3 4497.8 4522.6 3644.4 4428.4 4436.1 4440.7 4461.7 3672.9 4175.8 4416.6 4419.4 4428.9 3249.2 3769.7 3837.3 3837.5 3840.9 5363.0 5849.8 6003.7 6007.9 6067.4 4607.8 5252.9 5386.6 5390.1 5423.6 6322.2 6594.5 6594.9 6673.4 6695.2 3638.9 4368.4 4368.9 4378.0 4388.7 5404.4 6036.8 6678.2 6997.9 7150.6 4159.7 4276.4 4451.3 4456.8 4490.3 3983.9 4290.2 4410.9 4527.3 4554.9 4570.1 4675.8 4967.1 5286.9 5340.3 5215.7 5843.4 5940.6 6041.8 6728.3 1163.4 1359.7 1514.6 1520.7 1524.8 867.2 934.1 995.4 996.8 1004.2 4960.4 6177.2 6190.4 6192.8 6204.9 6709.8 6772.5 6788.7 6810.6 6840.9 5441.9 5739.9 5915.6 6146.3 6183.9 3207.5 3514.8 3590.4 3590.5 3607.5 6917.6 6966.9 7021.7 7057.7 7104.7 6179.8 6796.4 7071.8 7090.0 7094.4 5362.4 5772.5 5890.0 7075.5 7108.9 5954.9 6792.4 6884.9 6911.0 6944.6 6283.1 6573.3 6747.8 6814.8 6820.5 6680.5 6733.5 7118.1 7134.8 7185.6 4030.9 4255.3 4371.7 4417.5 4423.8 6888.9 6906.4 6921.2 6922.2 6948.4 6054.0 6243.5 6528.2 6575.9 6596.4 6647.4 6668.3 6669.0 6681.6 6842.8 5051.9 5342.4 6374.6 6427.3 6553.7 6128.3 6245.6 6592.5 6654.6 6662.1 5912.5 6283.1 6302.5 6394.3 6486.3 02:29:45 INFO - PID 6784 | 02:29:45 INFO - PID 6784 | [#5] yui.html Cycles:180 Average:24855.23 Median:17883.87 stddev:37114.58 (207.5%) stddev-sans-first:37174.86 02:29:45 INFO - PID 6784 | Values: 830.0 839.2 843.2 859.8 870.6 16722.0 17179.3 17219.6 18959.0 19764.4 18394.1 18560.4 18689.0 18745.7 18748.0 984.2 992.6 1004.9 1009.7 1010.8 984.6 997.7 1002.5 1003.8 1003.8 1055.6 1059.2 1065.7 1067.6 1072.9 1049.0 1054.3 1059.9 1063.9 1066.4 772.3 785.0 785.0 787.0 787.4 16720.4 16854.4 16860.7 17685.0 17998.7 17467.8 17535.8 17581.4 17828.4 17939.4 14596.0 14600.2 14678.4 14705.6 14789.0 3513.1 3526.5 3558.0 3584.7 3600.6 148166.0 149399.0 149857.3 150945.0 151647.0 11510.0 13257.3 13576.0 13666.7 13689.6 26301.1 26401.6 26614.9 26682.0 27143.6 7763.3 7812.1 7847.8 7882.6 7890.0 3382.9 3781.3 3912.0 4000.1 4012.7 4252.4 4325.2 4359.1 4363.2 4373.8 923.5 954.1 957.3 961.9 962.3 503.0 611.6 627.4 639.9 655.2 9913.4 10527.7 11479.4 11510.5 12023.1 546.8 560.5 617.4 628.7 634.1 5315.7 5584.0 5627.7 5684.1 5757.4 120544.8 123162.0 123639.4 123672.4 124600.0 104768.0 104932.0 107599.5 107761.0 108310.9 77481.2 89623.0 91729.5 98744.0 99912.5 11983.8 12178.9 12502.4 12551.6 12830.6 44716.0 45095.9 45163.5 45242.0 45495.5 85491.6 85953.0 86691.0 86807.6 87632.7 16486.9 16556.9 16719.7 16743.3 16859.3 18353.0 18402.6 18485.0 18652.3 18669.3 16472.9 16585.3 16730.7 16988.2 17087.7 16486.7 16681.2 16768.6 16787.6 16865.9 16183.8 16298.7 16437.5 16760.9 16773.3 11173.9 11653.4 11996.0 12139.2 12140.3 11137.7 11489.8 12352.4 13284.8 13523.7 02:29:45 INFO - PID 6784 | -------- Summary: end -------- 02:29:45 INFO - PID 6784 | 02:29:45 INFO - TEST-INFO | 6784: exit 0 02:29:45 INFO - TEST-OK | dromaeo_css | took 1142232ms 02:29:45 INFO - TEST-START | kraken 02:29:45 INFO - operating with platform_type : mac_ 02:29:45 INFO - Initialising browser for kraken test... 02:29:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57800/getInfo.html -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp9Oaknz/profile 02:29:45 INFO - TEST-INFO | started process 6789 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57800/getInfo.html -foreground) 02:30:19 INFO - TEST-INFO | 6789: killed by SIGTERM 02:30:19 INFO - Browser initialized. 02:30:19 INFO - Running cycle 1/1 for kraken test... 02:30:19 INFO - TEST-INFO | started process 6794 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp9Oaknz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:20 INFO - PID 6794 | 2017-05-27 02:30:20.909 plugin-container[6795:125984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 02:30:20 INFO - PID 6794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:30:20 INFO - PID 6794 | 2017-05-27 02:30:20.910 plugin-container[6795:125984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 02:30:20 INFO - PID 6794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:30:20 INFO - PID 6794 | 2017-05-27 02:30:20.910 plugin-container[6795:125984] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:30:21 INFO - PID 6794 | 2017-05-27 02:30:21.633 plugin-container[6796:126029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 02:30:21 INFO - PID 6794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:30:21 INFO - PID 6794 | 2017-05-27 02:30:21.634 plugin-container[6796:126029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1f, name = 'com.apple.CFPasteboardClient' 02:30:21 INFO - PID 6794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:30:21 INFO - PID 6794 | 2017-05-27 02:30:21.634 plugin-container[6796:126029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:30:30 INFO - PID 6794 | RSS: Main: 242802688 02:30:30 INFO - PID 6794 | 02:30:33 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57800/tests/kraken/driver.html?testName=audio-beat-detection) 02:30:34 INFO - PID 6794 | RSS: Main: 256946176 02:30:34 INFO - PID 6794 | 02:30:37 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57800/tests/kraken/driver.html?testName=audio-dft) 02:30:37 INFO - PID 6794 | RSS: Main: 266166272 02:30:37 INFO - PID 6794 | 02:30:41 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57800/tests/kraken/driver.html?testName=audio-fft) 02:30:41 INFO - PID 6794 | RSS: Main: 269250560 02:30:41 INFO - PID 6794 | 02:30:43 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57800/tests/kraken/driver.html?testName=audio-oscillator) 02:30:44 INFO - PID 6794 | RSS: Main: 270073856 02:30:44 INFO - PID 6794 | 02:30:46 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57800/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:30:47 INFO - PID 6794 | RSS: Main: 271962112 02:30:47 INFO - PID 6794 | 02:30:49 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57800/tests/kraken/driver.html?testName=imaging-darkroom) 02:30:50 INFO - PID 6794 | RSS: Main: 277876736 02:30:50 INFO - PID 6794 | 02:30:53 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57800/tests/kraken/driver.html?testName=imaging-desaturate) 02:30:54 INFO - PID 6794 | RSS: Main: 280760320 02:30:54 INFO - PID 6794 | 02:30:57 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57800/tests/kraken/driver.html?testName=json-parse-financial) 02:30:58 INFO - PID 6794 | RSS: Main: 279580672 02:30:58 INFO - PID 6794 | 02:30:59 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57800/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:31:00 INFO - PID 6794 | RSS: Main: 279212032 02:31:00 INFO - PID 6794 | 02:31:02 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:31:02 INFO - PID 6794 | RSS: Main: 279236608 02:31:02 INFO - PID 6794 | 02:31:04 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:31:05 INFO - PID 6794 | RSS: Main: 279101440 02:31:05 INFO - PID 6794 | 02:31:08 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:31:09 INFO - PID 6794 | RSS: Main: 278958080 02:31:09 INFO - PID 6794 | 02:31:12 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:31:13 INFO - PID 6794 | RSS: Main: 278933504 02:31:13 INFO - PID 6794 | 02:31:14 INFO - PID 6794 | Cycle 1(1): loaded http://localhost:57800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57800/tests/kraken/driver.html?testName=ai-astar) 02:31:15 INFO - PID 6794 | RSS: Main: 280698880 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | __start_tp_report 02:31:15 INFO - PID 6794 | _x_x_mozilla_page_load 02:31:15 INFO - PID 6794 | _x_x_mozilla_page_load_details 02:31:15 INFO - PID 6794 | |i|pagename|runs| 02:31:15 INFO - PID 6794 | |0;ai-astar;101;114;113;112;113;113;113;110;128;114 02:31:15 INFO - PID 6794 | |1;audio-beat-detection;150;112;111;152;108;112;111;112;141;114 02:31:15 INFO - PID 6794 | |2;audio-dft;199;221;246;279;213;221;223;206;199;196 02:31:15 INFO - PID 6794 | |3;audio-fft;71;50;62;111;50;70;86;87;90;89 02:31:15 INFO - PID 6794 | |4;audio-oscillator;92;70;83;72;69;70;91;86;102;106 02:31:15 INFO - PID 6794 | |5;imaging-gaussian-blur;91;91;91;90;92;88;91;90;92;90 02:31:15 INFO - PID 6794 | |6;imaging-darkroom;97;98;99;100;152;106;153;154;98;98 02:31:15 INFO - PID 6794 | |7;imaging-desaturate;124;97;146;140;91;92;92;90;95;91 02:31:15 INFO - PID 6794 | |8;json-parse-financial;52;50;50;50;51;51;55;52;51;51 02:31:15 INFO - PID 6794 | |9;json-stringify-tinderbox;56;63;75;92;61;58;56;58;56;59 02:31:15 INFO - PID 6794 | |10;stanford-crypto-aes;86;86;90;88;88;86;88;90;121;80 02:31:15 INFO - PID 6794 | |11;stanford-crypto-ccm;137;153;160;161;143;141;155;132;151;154 02:31:15 INFO - PID 6794 | |12;stanford-crypto-pbkdf2;168;230;220;192;202;190;186;187;186;186 02:31:15 INFO - PID 6794 | |13;stanford-crypto-sha256-iterative;60;61;94;94;61;60;86;84;93;93 02:31:15 INFO - PID 6794 | __end_tp_report 02:31:15 INFO - PID 6794 | __start_cc_report 02:31:15 INFO - PID 6794 | _x_x_mozilla_cycle_collect,1075 02:31:15 INFO - PID 6794 | __end_cc_report 02:31:15 INFO - PID 6794 | __startTimestamp1495877475146__endTimestamp 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | ------- Summary: start ------- 02:31:15 INFO - PID 6794 | Number of tests: 14 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:6.51 (5.8%) stddev-sans-first:5.22 02:31:15 INFO - PID 6794 | Values: 101.0 114.0 113.0 112.0 113.0 113.0 113.0 110.0 128.0 114.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:113.00 stddev:17.78 (15.7%) stddev-sans-first:15.79 02:31:15 INFO - PID 6794 | Values: 150.0 112.0 111.0 152.0 108.0 112.0 111.0 112.0 141.0 114.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#2] audio-dft Cycles:10 Average:220.30 Median:221.00 stddev:25.54 (11.6%) stddev-sans-first:25.90 02:31:15 INFO - PID 6794 | Values: 199.0 221.0 246.0 279.0 213.0 221.0 223.0 206.0 199.0 196.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#3] audio-fft Cycles:10 Average:76.60 Median:78.50 stddev:19.48 (24.8%) stddev-sans-first:20.56 02:31:15 INFO - PID 6794 | Values: 71.0 50.0 62.0 111.0 50.0 70.0 86.0 87.0 90.0 89.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:77.50 stddev:13.69 (17.7%) stddev-sans-first:14.22 02:31:15 INFO - PID 6794 | Values: 92.0 70.0 83.0 72.0 69.0 70.0 91.0 86.0 102.0 106.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:91.00 stddev:1.17 (1.3%) stddev-sans-first:1.24 02:31:15 INFO - PID 6794 | Values: 91.0 91.0 91.0 90.0 92.0 88.0 91.0 90.0 92.0 90.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#6] imaging-darkroom Cycles:10 Average:115.50 Median:97.50 stddev:26.00 (26.7%) stddev-sans-first:26.70 02:31:15 INFO - PID 6794 | Values: 97.0 98.0 99.0 100.0 152.0 106.0 153.0 154.0 98.0 98.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:91.50 stddev:22.06 (24.1%) stddev-sans-first:22.39 02:31:15 INFO - PID 6794 | Values: 124.0 97.0 146.0 140.0 91.0 92.0 92.0 90.0 95.0 91.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:1.49 (2.9%) stddev-sans-first:1.56 02:31:15 INFO - PID 6794 | Values: 52.0 50.0 50.0 50.0 51.0 51.0 55.0 52.0 51.0 51.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:60.00 stddev:11.55 (19.2%) stddev-sans-first:11.94 02:31:15 INFO - PID 6794 | Values: 56.0 63.0 75.0 92.0 61.0 58.0 56.0 58.0 56.0 59.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:88.00 stddev:11.16 (12.7%) stddev-sans-first:11.72 02:31:15 INFO - PID 6794 | Values: 86.0 86.0 90.0 88.0 88.0 86.0 88.0 90.0 121.0 80.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:153.50 stddev:9.88 (6.4%) stddev-sans-first:9.53 02:31:15 INFO - PID 6794 | Values: 137.0 153.0 160.0 161.0 143.0 141.0 155.0 132.0 151.0 154.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.70 Median:191.00 stddev:18.16 (9.5%) stddev-sans-first:16.49 02:31:15 INFO - PID 6794 | Values: 168.0 230.0 220.0 192.0 202.0 190.0 186.0 187.0 186.0 186.0 02:31:15 INFO - PID 6794 | 02:31:15 INFO - PID 6794 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.60 Median:89.50 stddev:15.93 (17.8%) stddev-sans-first:15.41 02:31:15 INFO - PID 6794 | Values: 60.0 61.0 94.0 94.0 61.0 60.0 86.0 84.0 93.0 93.0 02:31:15 INFO - PID 6794 | -------- Summary: end -------- 02:31:15 INFO - PID 6794 | 02:31:15 INFO - TEST-INFO | 6794: exit 0 02:31:15 INFO - TEST-OK | kraken | took 90294ms 02:31:15 INFO - SUITE-END | took 1232s 02:31:16 INFO - Completed test suite (00:20:32) 02:31:16 INFO - javascript benchmark 02:31:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7856.971338985837, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3556.679898809107, 3561.483584880192, 3567.6432356764312, 3584.1039740064984, 3598.9100272493183, 6184.56708030438, 6215.8446038849, 6276.529260305484, 6284.277308109568, 6300.684965751711, 6192.845178870531, 6215.316315205324, 6221.688915554227, 6224.502039836813, 6284.340144284842, 5401.567874570044, 5495.862603434926, 5499.945000549983, 5505.779768809243, 5530.170474428827, 5557.944420555803, 5615.073512870372, 5623.578231632644, 5664.0937450007805, 5678.26182596264, 5414.242006119146, 5528.308961379827, 5559.555235581144, 5609.80365687201, 5628.831135065954, 5462.617616766828, 5501.752421141058, 5513.448655134494, 5556.333240011184, 5556.583256255756, 5466.726663666801, 5478.671279723229, 5484.643498172646, 5500.917486237709, 5522.392536820947, 5104.948950510485, 6299.401556852092, 6305.621662700207, 6306.590071645328, 6328.303886572473, 6499.122618446497, 6583.604983700993, 6631.966840165816, 6748.4938629602975, 6775.457963362919, 6878.58728476293, 6898.206706228798, 6922.203946546161, 6922.58464492132, 6922.930770692279, 6350.555461117724, 6378.681065946684, 6381.234251889757, 6389.041643753474, 6392.009238568029, 6745.291744366771, 6858.177018757781, 6885.277045910208, 6992.405645520188, 7021.122359705037, 6316.526260530387, 6482.416582507597, 6606.6036037837885, 6669.499787515956, 6697.196336439561, 5724.398938111522, 6770.086038384854, 6862.4853136015, 6874.725010999504, 6877.277885822016, 6701.195856497154, 6714.731410743613, 6719.899201511981, 6725.19297684281, 6753.3246675332075, 6477.546571739933, 6734.191896972395, 6757.0, 6759.357861003196, 6759.898601520981, 5347.0375332440535, 5356.303680521507, 5377.8117765878005, 5407.702576358338, 5441.891162176735, 4791.305260737243, 4797.544233297831, 4800.73595952226, 4845.200541910545, 4850.466448690641, 4744.620430365631, 6198.752049917953, 6334.588251763621, 6335.176427064452, 6338.366163383625, 6579.342065793382, 6680.96659516709, 6749.5612785168805, 6834.931650683429, 6893.138357705287, 6283.560150789401, 6321.96839015802, 6333.1450254216015, 6338.841528961812, 6347.365263473708, 4668.836590719308, 6005.549583781234, 6050.9394906050375, 6064.0, 6073.240844894388, 6768.763093291711, 6890.655467226518, 6932.202796678446, 6978.895316570255, 7038.824029399306, 6757.695903684445, 6809.863802724017, 6846.0, 6846.863062738817, 6857.7256909723055, 6914.55055421396, 6998.265182156004, 7021.122359705037, 7027.367536921702, 7052.0832291801735, 6472.870542589216, 6565.638889861103, 6714.892042812881, 6754.054432379569, 6767.086443330088, 6726.529142960141, 6801.693923773343, 6849.0, 6875.656217189021, 6883.070785444102, 6635.601863888182, 6647.102641143384, 6664.400203981665, 6666.899996499959, 6675.4325882300545, 6321.778737744157, 6478.060681201294, 6490.156279683611, 6595.043718660863, 6627.768028118992, 6419.358064193543, 6494.415502604788, 6533.640649764308, 6537.1501704778075, 6541.6075035498025, 6642.0, 6678.3989440950545, 6678.632675202911, 6692.933070669231, 6698.56459330142, 6304.117423560797, 6366.076918846834, 6391.041343798467, 6467.256265529524, 6488.318726533646], "value": 6169.916539869586, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.9480683755555, 833.1252185205534, 850.561960590296, 1262.6843289177705, 1321.3063141850532, 38479.0, 38959.02602434933, 39000.60999390012, 39037.41443878344, 39161.02097447565, 32406.0, 33750.49374259382, 36761.63238367616, 36919.0, 39241.01897452565, 7825.295723384894, 8052.194780521931, 8255.298299644537, 8268.627911743986, 8269.710560130397, 8264.917350826505, 8376.49741015538, 8384.455010424303, 8394.496330220176, 8400.621972011244, 8337.541435221061, 8374.665013399457, 8387.706430274942, 8425.1153628869, 8426.199511046441, 7276.417886569062, 8165.550894700789, 8362.289205417517, 8375.49747015175, 8405.621747021369, 7934.404919630993, 8060.314873235782, 8083.838323233561, 8146.0, 8146.3890208234025, 36318.81840590807, 36356.63643363585, 36374.90875273746, 36450.635493645255, 36541.0, 3636.563612366533, 3670.504481894963, 3672.944905826415, 3675.099600597844, 3679.3193259247123, 29885.40229195404, 30111.247218819706, 30135.547966780516, 30233.0, 30264.0, 3035.4991426414617, 3096.179512429208, 3102.953455698166, 3112.1597168764533, 3124.7343975762087, 201437.0, 213478.0, 213648.0, 255823.0, 260263.0, 45989.5401045992, 46035.53964460312, 46056.8485787858, 46397.76801115973, 46424.53575464202, 48504.5149548507, 48544.786380340774, 48573.51426485761, 48592.0, 48606.51393486091, 14073.2963351832, 14201.147931123958, 14211.36048877803, 14213.573592792232, 14225.430982760781, 11466.0, 11599.362035087983, 11606.129540284512, 11617.361045142598, 11629.709257268636, 10791.730206744895, 13221.801672974721, 13231.603051908458, 13277.336133193496, 13405.932970335087, 6092.360302168208, 6117.694115294217, 6156.0, 6158.753649853956, 6160.44555989963, 927.8680010387317, 994.6866736977829, 1008.7730260691433, 1027.6095083868083, 1059.8940105989493, 23856.16503422372, 24577.754222457905, 24588.87705561461, 24606.0, 24721.752782472304, 1122.5846436818267, 1123.550579768083, 1135.7387800805861, 1152.1877076660935, 1178.7701398227434, 12457.0, 12493.12548121641, 12575.119741617998, 12622.495100195889, 12648.494060237488, 279725.0, 279899.0, 280167.0, 280807.0, 282824.0, 52054.95890082177, 52107.47892521102, 52141.95716085657, 52329.73835130876, 52495.73752131215, 1484.747592909208, 1515.5377609828888, 1549.449945269413, 1552.1385630974692, 1561.6642421879124, 23719.407014824767, 24003.639945400482, 24021.399465013514, 24031.399215019763, 24156.87921560381, 36525.45211821878, 36536.81731591325, 36661.45007824938, 36677.63322366742, 36720.449193261586, 214123.0, 214777.0, 215557.0, 215919.0, 216631.0, 32711.836440817642, 32864.835675821465, 33489.665103348656, 34198.0, 34379.82810085933, 42734.786326069414, 44514.55485445104, 44879.775601121786, 44890.1021979552, 44897.0, 28878.24582804222, 29545.409091818474, 30419.847900760353, 31160.065198044096, 31254.374912501167, 30177.0, 30707.692923070485, 30725.53911691371, 30825.383492330475, 31419.05742827719, 30209.0, 35773.64226357703, 36019.45970810492, 36621.0, 37032.44451333286, 29792.851035744683, 30330.39339213159, 31033.069007928894, 31280.843595781877, 31297.061088167393, 19182.0, 23904.282871513882, 24153.15463958753, 24397.02411903575, 24895.502089957736], "value": 17068.087146427846, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.3405369254801, 396.8234135809564, 406.83036270721675, 436.8881197352737, 450.2301065178545, 19343.613127737437, 21058.89470552647, 21748.782512174872, 21992.89003554982, 22141.557168856612, 22640.20759273426, 23028.65457018146, 23705.64441533374, 23713.695746733923, 23937.521249575, 5989.970050149754, 6209.285932117797, 6240.469560087398, 6242.313345531988, 6247.219097612799, 5867.178594996704, 6115.571909966304, 6118.816435506942, 6199.163112979735, 6212.409821066992, 5242.501962313574, 6019.7893073742425, 6079.726412311456, 6089.482393996515, 6111.113888486167, 4967.7764500597195, 6010.489108425767, 6065.302490213616, 6101.908471372933, 6107.877842443148, 5377.892442151135, 5382.381026181961, 5439.129739241742, 5439.592030597721, 5441.428649991773, 27359.45281094367, 27505.86247068752, 27671.30821729453, 27724.445511089867, 27820.443591128267, 2130.6697461893277, 2212.856164349312, 2224.566209589115, 2228.7882651148116, 2229.108356657334, 16138.596535086599, 17414.651706965917, 18137.0, 18277.908610456994, 18433.0, 1941.0197850085758, 2130.2970019893396, 2136.2629892686996, 2153.0203757290396, 2191.583599116163, 139864.0, 149719.0, 152073.0, 153864.0, 157147.0, 12459.875401245872, 12730.490780368867, 12850.807237891438, 12917.612471625866, 12962.935185324013, 16688.6662266754, 16818.24317905697, 16824.495265142064, 16827.326906923587, 16884.662306753795, 8956.955215223883, 9016.8647470288, 9111.0, 9191.540422978824, 9286.257099432029, 8639.481631102153, 8703.216710496086, 8722.95638521816, 8747.606357713918, 8839.867401988975, 6858.588484690935, 8069.677212911535, 8187.263146316741, 8199.672013119527, 8212.301954333829, 2185.661222510513, 2187.387531491185, 2188.529466164782, 2196.330119313625, 2358.0803486640225, 893.9642414303355, 919.6045700348914, 961.6874515782399, 1001.6677818501375, 1008.3344992305043, 20484.0, 21184.788152118283, 21374.572508549743, 21482.462938426354, 21521.354359369245, 894.3337213775778, 957.9521023948774, 989.8319982420669, 1022.1669339488293, 1026.1277913773383, 9496.670466134608, 10603.310784798963, 10745.570177192825, 10760.677179684622, 11059.059979901798, 367373.0, 382714.0, 449010.0, 453578.0, 457814.0, 113856.0451152617, 116355.0, 117948.23077653842, 118076.0, 119042.0, 1080.3247970018738, 1138.1407037686658, 1159.3971135009917, 1167.1596450555587, 1201.1291813435364, 22478.662820058038, 22748.09007639676, 22919.42701432411, 22980.655290170995, 23137.537249255256, 55634.721826392226, 56410.43589564216, 56557.30328090164, 56998.7150064247, 57206.41903714476, 57050.85898281928, 72376.0, 75526.62236688781, 76200.0, 76338.61830690812, 8894.68868589622, 9009.0, 9017.143371379709, 9160.496172710566, 9190.816183676423, 13516.459341626225, 13585.79621305701, 14027.719445611236, 14082.507112251022, 14233.430662773788, 11705.483321086007, 11727.179097463095, 11831.053515718893, 11975.940120299343, 12040.21738586989, 10760.0, 10787.892121079003, 10949.726256843642, 10963.725906852074, 11047.668569942914, 11779.352135632622, 11844.526218951145, 11943.343116128697, 11983.64049078529, 12043.337616431181, 7389.593572353572, 8650.394472386872, 8824.11758824138, 8974.429474841745, 9000.009998899985, 10088.69733907984, 11327.15046371509, 11403.429828508375, 12051.096167787276, 12319.076069294659], "value": 10320.288939211177, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.7279198576314, 1455.4032846532923, 1478.8225412950449, 1489.9404023839047, 1505.3602219056897, 9384.296177786668, 11797.0, 11824.290542567453, 11844.34856082915, 11966.461509232084, 11754.471048802803, 11882.168248222628, 11955.581554645589, 11983.161178717493, 11991.280523168616, 2528.051980507305, 2565.6279839423273, 2575.35616095976, 2575.484903019394, 2577.90977315794, 2505.5615267328235, 2519.143491212987, 2537.784288335494, 2539.4540173862615, 2545.5545279576095, 2495.500899820034, 2505.711843138035, 2518.131244720578, 2528.8609126498127, 2602.3624212067975, 2455.693038370204, 2483.4908843687, 2489.651448797161, 2504.499100179962, 2507.5987841945384, 2449.3141920262356, 2497.0386401235655, 2505.7619526144986, 2512.0830896722746, 2552.5660637691635, 11553.480093395818, 11658.358790266531, 11669.708257293636, 11670.35813030283, 11680.824787628193, 6484.740610375579, 6652.833679158011, 6716.496262780262, 6732.091167692348, 6759.121314229168, 3583.94624080639, 3672.412414013745, 4073.348264277731, 4228.217779710763, 4298.247806633826, 1237.8824011718962, 1241.7081985733346, 1245.4395522015038, 1254.316397563321, 1258.937053147339, 15270.236488175658, 15537.145456999973, 16169.514914552583, 16173.110478923654, 16355.59111022222, 4489.2592722201125, 5469.945300547023, 5669.788782374877, 5682.6306290091015, 5698.059820129715, 11620.418979051014, 11653.708657283467, 11661.416929153509, 11663.941680291544, 11675.941620291844, 6352.555321127569, 6391.296957334689, 6453.548251622436, 6455.838604034937, 6480.967595162088, 6011.939880601226, 6127.846803829851, 6130.877382452326, 6160.969195154085, 6215.564910456315, 4311.417958575552, 4352.542982986741, 4440.666949978699, 4481.910361792811, 4484.148011877733, 1245.5578269714301, 1246.6634008817568, 1265.6709255593612, 1279.6864768131682, 1280.3406245783428, 791.6200223892564, 792.0930534537922, 816.1389733830747, 820.6963423533331, 841.7432683031734, 6243.063540468966, 6270.717817698214, 6466.288708242089, 6511.674416279167, 6550.737970481127, 579.7796837201921, 602.6233603997501, 604.4137186928672, 604.4831668923096, 616.8334549671564, 3971.62269584389, 4001.279769641434, 4062.532808727034, 4336.284513055373, 4577.725336479822, 14022.719545609234, 14560.708785824225, 14585.781213281809, 14678.192699401421, 14728.263586820616, 11201.719957000978, 14548.490802822062, 14593.85406145925, 14611.634709132357, 15114.017588856688, 8327.625256863455, 8359.996800383993, 8403.033651130076, 8458.703945361884, 8502.107278735768, 5968.522518198621, 6323.988161894074, 6816.147981502312, 7125.82185445368, 7148.320909513588, 10587.682369528926, 13369.465221391425, 13513.054086214264, 13651.931740341235, 13660.316984150952, 10856.294340867818, 13351.265680387673, 13429.529966451519, 13911.513097041556, 13930.303484825921, 7469.925300747141, 9318.627254909728, 9373.953130234306, 9410.764730881783, 9413.529323533932, 10562.683119506428, 10586.10018148466, 10638.787224255626, 10644.254902156774, 10657.040866322068, 8827.426217295855, 9141.954290228507, 9176.26589872822, 9206.0, 9237.769055773659, 8933.642654294015, 8953.910460895568, 9017.90982090197, 9039.096090390909, 9108.407953483267, 8069.556174410229, 8291.58542072882, 8347.373946954123, 8435.536045517556, 8472.237498625153, 6066.908996365147, 6153.600015998945, 6173.259208894961, 6282.905756413749, 6296.338884417253, 5486.61593688438, 5833.708314584339, 6270.655113968775, 6320.968395157995, 6362.363763623601], "value": 5282.2066393119385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.72138542931225, 369.6950016236606, 387.4749714137344, 389.7271909663236, 399.0263756434302, 5042.344495215617, 6065.332813390523, 6287.119803227546, 6300.275468321144, 6359.6184228946295, 5232.424433312332, 5447.665321996116, 5461.853010867722, 5653.0, 6169.629822210671, 3746.838480071173, 4425.579569940851, 4478.126765280772, 4487.573680500347, 4498.482674492426, 3760.5863355030924, 4373.890652733675, 4483.260262056757, 4497.775111244425, 4522.592966633002, 3644.398674218751, 4428.446444194476, 4436.112777444508, 4440.733555986635, 4461.709988850731, 3672.9164896355505, 4175.832966681344, 4416.646668266531, 4419.359192917009, 4428.933565996513, 3249.23642943908, 3769.738157105742, 3837.3092843288196, 3837.520309961255, 3840.9245411284883, 5363.034653762321, 5849.824505264849, 6003.699815009232, 6007.88854062, 6067.362926892701, 4607.792649330788, 5252.923150754086, 5386.569074474032, 5390.1375779875425, 5423.589866634703, 6322.209723784527, 6594.53838231324, 6594.934050659528, 6673.366030227169, 6695.163104611924, 3638.909027274313, 4368.400801523088, 4368.89077773055, 4377.9781101094295, 4388.736675799462, 5404.405515393303, 6036.8188954331445, 6678.232003319632, 6997.895031574531, 7150.642467876637, 4159.667226621863, 4276.379924910871, 4451.265541185668, 4456.774387043524, 4490.3040028795585, 3983.8605648802154, 4290.237836002036, 4410.875226817206, 4527.3435351873795, 4554.863354099382, 4570.085982803453, 4675.766211689402, 4967.130752118364, 5286.920696189561, 5340.279062326614, 5215.687058776485, 5843.444872737083, 5940.6435613863305, 6041.761438905014, 6728.259891411941, 1163.4473625028045, 1359.6736783171968, 1514.5914299701383, 1520.7338715724704, 1524.839891811366, 867.2324992381662, 934.065934065934, 995.4326034160604, 996.7707427291764, 1004.2217281606813, 4960.355153829979, 6177.1969643945995, 6190.350013248634, 6192.845178870474, 6204.906926396107, 6709.798706038827, 6772.458203343819, 6788.728450862009, 6810.6254156020905, 6840.863182736317, 5441.863953401196, 5739.885202295931, 5915.585908986416, 6146.293176284785, 6183.866566034711, 3207.486802111651, 3514.806685632315, 3590.4255319149333, 3590.5152804371596, 3607.549056367954, 6917.575799979043, 6966.895496567556, 7021.719131234796, 7057.717691292393, 7104.7158113675905, 6179.84550386235, 6796.388325050671, 7071.82320441993, 7090.0, 7094.361507464249, 5362.436944120811, 5772.480476757112, 5890.028145356054, 7075.478772063928, 7108.893366599608, 5954.910676339945, 6792.403785110397, 6884.8967265490055, 6911.0, 6944.583325000516, 6283.088952102011, 6573.309802470863, 6747.763828265987, 6814.795556133324, 6820.522563420513, 6680.498962577729, 6733.494987875831, 7118.110236220473, 7134.785956421316, 7185.568865868065, 4030.879073627796, 4255.276602977563, 4371.672124590732, 4417.491988421372, 4423.756693381895, 6888.862222755617, 6906.412954898889, 6921.227930777839, 6922.169339679271, 6948.374646281859, 6054.031354983182, 6243.469305109116, 6528.204743695522, 6575.914974029231, 6596.406323430914, 6647.401733843977, 6668.266490685924, 6668.966655166693, 6681.632510211985, 6842.836717757894, 5051.924221136759, 5342.408931668216, 6374.597588530516, 6427.260865000398, 6553.705083271359, 6128.264608247039, 6245.5628106032145, 6592.505562082959, 6654.600723956578, 6662.135961142622, 5912.526997840248, 6283.088952102011, 6302.464290535356, 6394.296627370893, 6486.286508484157], "value": 4701.092526501042, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.9709510167138, 839.1734141870261, 843.1526316052366, 859.7549698335976, 870.6430363550945, 16722.0, 17179.312827486887, 17219.569510762223, 18959.0, 19764.407067787954, 18394.080295985215, 18560.443186704386, 18689.0, 18745.7188142178, 18748.0, 984.212629896082, 992.5682328187225, 1004.8743907011624, 1009.7071849163771, 1010.8079464901682, 984.6159997600905, 997.7306127345612, 1002.5488530161421, 1003.7841863999239, 1003.8193125237455, 1055.5566662001938, 1059.2214722179192, 1065.7069305940852, 1067.5996501312009, 1072.8819829818754, 1049.01916707878, 1054.3410368519676, 1059.9470026498682, 1063.9095676867514, 1066.4241309692757, 772.3396495995898, 784.9803754906116, 784.9882251766228, 786.9926494087578, 787.3779714025912, 16720.41478548245, 16854.410095646715, 16860.712161128584, 17685.0, 17998.730019049726, 17467.825321746746, 17535.824641753676, 17581.384651537264, 17828.3760068397, 17939.372121975797, 14596.0, 14600.196989165592, 14678.412863485448, 14705.632359191106, 14789.0, 3513.104158439607, 3526.5415495985612, 3557.964420355789, 3584.7490675652716, 3600.567931848195, 148166.0, 149399.0, 149857.25071374682, 150945.0, 151646.9999999989, 11510.0, 13257.270850103338, 13576.0, 13666.658333541742, 13689.589312320646, 26301.079462219113, 26401.603975940376, 26614.935402584066, 26682.0, 27143.592846107324, 7763.262490063435, 7812.140664526897, 7847.803804904923, 7882.605869706492, 7889.960550197212, 3382.9323413532084, 3781.3193625147187, 3911.9804400977814, 4000.099977505038, 4012.6990475713856, 4252.4046633471335, 4325.199838029975, 4359.06280149763, 4363.214621368184, 4373.846915357947, 923.4690053219425, 954.1365064616533, 957.2916042128775, 961.9422834629945, 962.3215632978734, 503.01660254202943, 611.5657882903099, 627.4478458956089, 639.8688268904864, 655.1875674164097, 9913.405195688281, 10527.736806579896, 11479.36863472517, 11510.518163193823, 12023.098267629963, 546.8386825886373, 560.4675558219708, 617.3918690090258, 628.6982248520736, 634.1344065350855, 5315.654482458628, 5584.022796010682, 5627.662340259598, 5684.147377893267, 5757.424257574293, 120544.79455205511, 123162.0, 123639.38180309221, 123672.38163809123, 124600.0, 104768.0, 104932.0, 107599.46200268948, 107761.0, 108310.91689083008, 77481.22518774853, 89623.0, 91729.54135229281, 98744.01255987491, 99912.50831171028, 11983.76032479328, 12178.939105304416, 12502.43077889654, 12551.623451296477, 12830.55093071738, 44716.0, 45095.872603185184, 45163.54836451593, 45242.0, 45495.54504454913, 85491.57254213688, 85953.0, 86691.0, 86807.56596216979, 87632.68550971613, 16486.917565412095, 16556.917215413847, 16719.665606688042, 16743.33026678919, 16859.325626974784, 18352.990585517924, 18402.631947361246, 18485.0, 18652.253909843454, 18669.34657287043, 16472.917635412225, 16585.336586536887, 16730.665386692443, 16988.235529401452, 17087.743683845, 16486.67026659484, 16681.2493437798, 16768.58078548046, 16787.580310492336, 16865.91567042157, 16183.838161618232, 16298.67402651964, 16437.506874793777, 16760.916195418944, 16773.32906683719, 11173.944130279297, 11653.41732913368, 11996.0, 12139.210951288138, 12140.271583705005, 11137.665870023913, 11489.770204596029, 12352.4441400139, 13284.80072798928, 13523.72952540924], "value": 8716.259409821398, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0, 110.0, 128.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 111.0, 152.0, 108.0, 112.0, 111.0, 112.0, 141.0, 114.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 221.0, 246.0, 279.0, 213.0, 221.0, 223.0, 206.0, 199.0, 196.0], "value": 220.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 50.0, 62.0, 111.0, 50.0, 70.0, 86.0, 87.0, 90.0, 89.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 83.0, 72.0, 69.0, 70.0, 91.0, 86.0, 102.0, 106.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 90.0, 92.0, 88.0, 91.0, 90.0, 92.0, 90.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 99.0, 100.0, 152.0, 106.0, 153.0, 154.0, 98.0, 98.0], "value": 115.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 97.0, 146.0, 140.0, 91.0, 92.0, 92.0, 90.0, 95.0, 91.0], "value": 105.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 50.0, 51.0, 51.0, 55.0, 52.0, 51.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 75.0, 92.0, 61.0, 58.0, 56.0, 58.0, 56.0, 59.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 90.0, 88.0, 88.0, 86.0, 88.0, 90.0, 121.0, 80.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 153.0, 160.0, 161.0, 143.0, 141.0, 155.0, 132.0, 151.0, 154.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 230.0, 220.0, 192.0, 202.0, 190.0, 186.0, 187.0, 186.0, 186.0], "value": 194.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 94.0, 94.0, 61.0, 60.0, 86.0, 84.0, 93.0, 93.0], "value": 78.6, "unit": "score"}]}]} 02:31:16 INFO - Return code: 0 02:31:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:31:16 INFO - # TBPL SUCCESS # 02:31:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:31:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:31:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:31:16 INFO - 2017-05-27 02:31:16.419 screenresolution[6801:126414] starting screenresolution argv=screenresolution get 02:31:16 INFO - 2017-05-27 02:31:16.421 screenresolution[6801:126414] Display 0: 1600x1200x32@60 02:31:16 INFO - 2017-05-27 02:31:16.427 screenresolution[6802:126417] starting screenresolution argv=screenresolution list 02:31:16 INFO - Available Modes on Display 0 02:31:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:31:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:31:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:31:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:31:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:31:16 INFO - Intel Iris: 02:31:16 INFO - Chipset Model: Intel Iris 02:31:16 INFO - Type: GPU 02:31:16 INFO - Bus: Built-In 02:31:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:31:16 INFO - Vendor: Intel (0x8086) 02:31:16 INFO - Device ID: 0x0a2e 02:31:16 INFO - Revision ID: 0x0009 02:31:16 INFO - Displays: 02:31:16 INFO - Display: 02:31:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:31:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:31:16 INFO - Display Serial Number: 1600x1200 60 02:31:16 INFO - Main Display: Yes 02:31:16 INFO - Mirror: Off 02:31:16 INFO - Online: Yes 02:31:16 INFO - Rotation: Supported 02:31:16 INFO - Return code: 0 02:31:16 INFO - Running post-action listener: _resource_record_post_action 02:31:16 INFO - [mozharness: 2017-05-27 09:31:16.663607Z] Finished run-tests step (success) 02:31:16 INFO - Running post-run listener: _resource_record_post_run 02:31:16 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 2446336; Write bytes: 563162112; Read time: 47; Write time: 1826 02:31:16 INFO - TinderboxPrint: CPU usage
53.4% 02:31:16 INFO - TinderboxPrint: I/O read bytes / time
2,446,336 / 47 02:31:16 INFO - TinderboxPrint: I/O write bytes / time
563,162,112 / 1,826 02:31:16 INFO - TinderboxPrint: CPU idle
2,340.0 (46.6%) 02:31:16 INFO - TinderboxPrint: CPU system
1,021.8 (20.3%) 02:31:16 INFO - TinderboxPrint: CPU user
1,659.9 (33.1%) 02:31:16 INFO - TinderboxPrint: Swap in / out
246,812,672 / 0 02:31:16 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228952576; Write bytes: 228611584; Read time: 16850; Write time: 478 02:31:16 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 2446336; Write bytes: 316085760; Read time: 47; Write time: 1302 02:31:17 INFO - Running post-run listener: _upload_blobber_files 02:31:17 INFO - Blob upload gear active. 02:31:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:31:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:31:17 INFO - (blobuploader) - INFO - Open directory for files ... 02:31:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:31:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:31:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:18 INFO - (blobuploader) - INFO - Done attempting. 02:31:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:31:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:31:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:19 INFO - (blobuploader) - INFO - Done attempting. 02:31:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:31:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:31:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:20 INFO - (blobuploader) - INFO - Done attempting. 02:31:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:31:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:22 INFO - (blobuploader) - INFO - Done attempting. 02:31:22 INFO - (blobuploader) - INFO - Iteration through files over. 02:31:22 INFO - Return code: 0 02:31:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:31:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:31:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fd9e277a2852396b4ae7518230fd5395957a8b129947e1fff8e6b0c25e8e4ad371bb6702c49371280b23025222cdd3b6040825f405a41e7a6d82fe64cae22e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75761a1ba51ccbb87079f6911b0f5b1b46fb75a1f50f0efb35fe651a6573a2277a0e65ffa629d31acf5c6e9837cf47a390d7ac8cf14b73e1370de2bf239df108", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc4083a7df4130f22753a51f5ab2207db495dc5f9d5567d03722091987c41867a841cf64cd08b0ee52b51acd2c97638ee88dd0f10f1e136c5ebd44fe8e49627f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a26a2876c35544cbd57cc2c13974d9b1a72d7fd37e5c6ba567ccb5bfc611f394933f2c629c7cc52a6ee9f73008f08ed796fa83c7d92be151cf9470703572d04"} 02:31:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:31:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:31:22 INFO - Contents: 02:31:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fd9e277a2852396b4ae7518230fd5395957a8b129947e1fff8e6b0c25e8e4ad371bb6702c49371280b23025222cdd3b6040825f405a41e7a6d82fe64cae22e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75761a1ba51ccbb87079f6911b0f5b1b46fb75a1f50f0efb35fe651a6573a2277a0e65ffa629d31acf5c6e9837cf47a390d7ac8cf14b73e1370de2bf239df108", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc4083a7df4130f22753a51f5ab2207db495dc5f9d5567d03722091987c41867a841cf64cd08b0ee52b51acd2c97638ee88dd0f10f1e136c5ebd44fe8e49627f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a26a2876c35544cbd57cc2c13974d9b1a72d7fd37e5c6ba567ccb5bfc611f394933f2c629c7cc52a6ee9f73008f08ed796fa83c7d92be151cf9470703572d04"} 02:31:22 INFO - Running post-run listener: copy_logs_to_upload_dir 02:31:22 INFO - Copying logs to upload dir... 02:31:22 INFO - mkdir: /builds/slave/test/build/upload/logs 02:31:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.014197 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-27 02:31:22.354272) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 02:31:22.357403) ========= 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.84jE1ey9ki/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.nx2Fti5C6o/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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/8fd9e277a2852396b4ae7518230fd5395957a8b129947e1fff8e6b0c25e8e4ad371bb6702c49371280b23025222cdd3b6040825f405a41e7a6d82fe64cae22e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75761a1ba51ccbb87079f6911b0f5b1b46fb75a1f50f0efb35fe651a6573a2277a0e65ffa629d31acf5c6e9837cf47a390d7ac8cf14b73e1370de2bf239df108", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc4083a7df4130f22753a51f5ab2207db495dc5f9d5567d03722091987c41867a841cf64cd08b0ee52b51acd2c97638ee88dd0f10f1e136c5ebd44fe8e49627f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a26a2876c35544cbd57cc2c13974d9b1a72d7fd37e5c6ba567ccb5bfc611f394933f2c629c7cc52a6ee9f73008f08ed796fa83c7d92be151cf9470703572d04"} build_url:https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010676 build_url: 'https://queue.taskcluster.net/v1/task/NPJQuN3jSrSTsYtFw45bZA/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/8fd9e277a2852396b4ae7518230fd5395957a8b129947e1fff8e6b0c25e8e4ad371bb6702c49371280b23025222cdd3b6040825f405a41e7a6d82fe64cae22e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75761a1ba51ccbb87079f6911b0f5b1b46fb75a1f50f0efb35fe651a6573a2277a0e65ffa629d31acf5c6e9837cf47a390d7ac8cf14b73e1370de2bf239df108", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc4083a7df4130f22753a51f5ab2207db495dc5f9d5567d03722091987c41867a841cf64cd08b0ee52b51acd2c97638ee88dd0f10f1e136c5ebd44fe8e49627f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a26a2876c35544cbd57cc2c13974d9b1a72d7fd37e5c6ba567ccb5bfc611f394933f2c629c7cc52a6ee9f73008f08ed796fa83c7d92be151cf9470703572d04"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 02:31:22.390239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 02:31:22.390608) ========= 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.84jE1ey9ki/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.nx2Fti5C6o/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005471 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 02:31:22.440498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 02:31:22.440871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 02:31:22.441469) ========= ========= Total master_lag: 0.64 =========