builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0039 starttime: 1475634040.21 results: success (0) buildid: 20161004172100 builduid: 2e42beacab544212b8734c54b3dc44cd revision: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.211470) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.212147) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.212591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.321281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.321543) ========= 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.WDxdMeuLto/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.Tn5r6USOeK/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 --2016-10-04 19:20:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.0M=0.001s 2016-10-04 19:20:40 (17.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.176210 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.527779) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.528106) ========= 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.WDxdMeuLto/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.Tn5r6USOeK/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.028283 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.600958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 19:20:40.601340) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cda7c3e91ad7eb8119e1ae352cf362d9afa19918 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cda7c3e91ad7eb8119e1ae352cf362d9afa19918 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WDxdMeuLto/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.Tn5r6USOeK/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 2016-10-04 19:20:40,676 truncating revision to first 12 chars 2016-10-04 19:20:40,677 Setting DEBUG logging. 2016-10-04 19:20:40,677 attempt 1/10 2016-10-04 19:20:40,677 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cda7c3e91ad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 19:20:41,674 unpacking tar archive at: mozilla-central-cda7c3e91ad7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.272671 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 19:20:41.899064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.899455) ========= script_repo_revision: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.899847) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.900110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.920107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-10-04 19:20:41.920397) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.WDxdMeuLto/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.Tn5r6USOeK/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:20:42 INFO - MultiFileLogger online at 20161004 19:20:42 in /builds/slave/test 19:20:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:20:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:20:42 INFO - {'append_to_log': False, 19:20:42 INFO - 'base_work_dir': '/builds/slave/test', 19:20:42 INFO - 'blob_upload_branch': 'Firefox', 19:20:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:20:42 INFO - 'branch': 'Firefox', 19:20:42 INFO - 'buildbot_json_path': 'buildprops.json', 19:20:42 INFO - 'config_files': ('talos/mac_config.py',), 19:20:42 INFO - 'default_actions': ('clobber', 19:20:42 INFO - 'read-buildbot-config', 19:20:42 INFO - 'download-and-extract', 19:20:42 INFO - 'populate-webroot', 19:20:42 INFO - 'create-virtualenv', 19:20:42 INFO - 'install', 19:20:42 INFO - 'run-tests'), 19:20:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:20:42 INFO - 'download_minidump_stackwalk': True, 19:20:42 INFO - 'download_symbols': 'ondemand', 19:20:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:20:42 INFO - 'tooltool.py': '/tools/tooltool.py', 19:20:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:20:42 INFO - '/tools/misc-python/virtualenv.py')}, 19:20:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:20:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:20:42 INFO - 'installer_path': 'installer.exe', 19:20:42 INFO - 'log_level': 'info', 19:20:42 INFO - 'log_name': 'talos', 19:20:42 INFO - 'log_to_console': True, 19:20:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:20:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:20:42 INFO - 'opt_config_files': (), 19:20:42 INFO - 'pip_index': False, 19:20:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:20:42 INFO - 'cmd': ('bash', 19:20:42 INFO - '-c', 19:20:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:20:42 INFO - 'enabled': True, 19:20:42 INFO - 'halt_on_failure': False, 19:20:42 INFO - 'name': 'check_screen_resolution'},), 19:20:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:20:42 INFO - 'cmd': ('bash', 19:20:42 INFO - '-c', 19:20:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:20:42 INFO - 'enabled': True, 19:20:42 INFO - 'halt_on_failure': False, 19:20:42 INFO - 'name': 'check_screen_resolution'},), 19:20:42 INFO - 'run_cmd_checks_enabled': True, 19:20:42 INFO - 'sps_profile': False, 19:20:42 INFO - 'sps_profile_interval': 0, 19:20:42 INFO - 'suite': 'dromaeojs', 19:20:42 INFO - 'system_bits': '32', 19:20:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:20:42 INFO - 'title': 't-yosemite-r7-0039', 19:20:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:20:42 INFO - 'use_talos_json': True, 19:20:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:20:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:20:42 INFO - 'work_dir': 'build'} 19:20:42 INFO - [mozharness: 2016-10-05 02:20:42.123599Z] Running clobber step. 19:20:42 INFO - Running pre-action listener: _resource_record_pre_action 19:20:42 INFO - Running main action method: clobber 19:20:42 INFO - rmtree: /builds/slave/test/build 19:20:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:20:44 INFO - Running post-action listener: _resource_record_post_action 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.311254Z] Finished clobber step (success) 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.311536Z] Running read-buildbot-config step. 19:20:44 INFO - Running pre-action listener: _resource_record_pre_action 19:20:44 INFO - Running main action method: read_buildbot_config 19:20:44 INFO - Using buildbot properties: 19:20:44 INFO - { 19:20:44 INFO - "project": "", 19:20:44 INFO - "product": "firefox", 19:20:44 INFO - "script_repo_revision": "production", 19:20:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:20:44 INFO - "repository": "", 19:20:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:20:44 INFO - "buildid": "20161004172100", 19:20:44 INFO - "pgo_build": "False", 19:20:44 INFO - "basedir": "/builds/slave/test", 19:20:44 INFO - "buildnumber": 32, 19:20:44 INFO - "slavename": "t-yosemite-r7-0039", 19:20:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:20:44 INFO - "platform": "yosemite_r7", 19:20:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:20:44 INFO - "branch": "mozilla-central", 19:20:44 INFO - "repo_path": "mozilla-central", 19:20:44 INFO - "revision": "cda7c3e91ad7eb8119e1ae352cf362d9afa19918", 19:20:44 INFO - "stage_platform": "macosx64", 19:20:44 INFO - "builduid": "2e42beacab544212b8734c54b3dc44cd", 19:20:44 INFO - "slavebuilddir": "test" 19:20:44 INFO - } 19:20:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:20:44 INFO - Running post-action listener: _resource_record_post_action 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.317290Z] Finished read-buildbot-config step (success) 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.317509Z] Running download-and-extract step. 19:20:44 INFO - Running pre-action listener: _resource_record_pre_action 19:20:44 INFO - Running main action method: download_and_extract 19:20:44 INFO - mkdir: /builds/slave/test/build/tests 19:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:20:44 INFO - trying https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:20:44 INFO - Downloading https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:20:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 19:20:45 INFO - Downloaded 1199 bytes. 19:20:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:20:45 INFO - Using the following test package requirements: 19:20:45 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:20:45 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:20:45 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:20:45 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'jsshell-mac.zip'], 19:20:45 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:20:45 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:20:45 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:20:45 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:20:45 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:20:45 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:20:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:20:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:20:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:20:45 INFO - Fetch https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:20:46 INFO - Http code: 200 19:20:46 INFO - via: 1.1 aa89533ad2ec5e0edba466c9920bd000.cloudfront.net (CloudFront) 19:20:46 INFO - x-amz-cf-id: tY9cS_KJcYYjqNtfQw6tcI9kGVd6GQVz2fV08KZYfOqsMtDTDjFGfA== 19:20:46 INFO - x-amz-version-id: 7r5MRpl34Z1bw3cePnzocwglbZOj6jJ9 19:20:46 INFO - Expected file size: 16476831 19:20:46 INFO - Obtained file size: 16476831 19:20:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:20:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:20:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:20:53 INFO - Fetch https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:20:53 INFO - Http code: 200 19:20:53 INFO - via: 1.1 990c804118e679d7ea0f4d3ca3cc4534.cloudfront.net (CloudFront) 19:20:53 INFO - x-amz-cf-id: ZuoeZocDb1YggK3AEi2d0uL_4EUVUmwBqfvUiXR3kfDJK1Mxru4OCQ== 19:20:53 INFO - x-amz-version-id: 7r5MRpl34Z1bw3cePnzocwglbZOj6jJ9 19:20:54 INFO - Expected file size: 16476831 19:20:54 INFO - Obtained file size: 16476831 19:21:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:21:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:21:00 INFO - Fetch https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:21:01 INFO - Http code: 200 19:21:01 INFO - via: 1.1 02192a27c967e955f8c815efa939bfc8.cloudfront.net (CloudFront) 19:21:01 INFO - x-amz-cf-id: eDnv4ek9D8HDsrdrJjEEXFIMhWsvAq8qCUAAHcQg2iPr8x4XgFFMtw== 19:21:01 INFO - x-amz-version-id: XobCOJzIponJYRjdli4AarZapzTrihz6 19:21:02 INFO - Expected file size: 14409006 19:21:02 INFO - Obtained file size: 14409006 19:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:02 INFO - trying https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:21:02 INFO - Downloading https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 19:21:09 INFO - Downloaded 111664185 bytes. 19:21:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:21:09 INFO - mkdir: /builds/slave/test/properties 19:21:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:21:09 INFO - Writing to file /builds/slave/test/properties/build_url 19:21:09 INFO - Contents: 19:21:09 INFO - build_url:https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:21:09 INFO - Running post-action listener: _resource_record_post_action 19:21:09 INFO - Running post-action listener: set_extra_try_arguments 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.971143Z] Finished download-and-extract step (success) 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.971299Z] Running populate-webroot step. 19:21:09 INFO - Running pre-action listener: _resource_record_pre_action 19:21:09 INFO - Running main action method: populate_webroot 19:21:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:21:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:21:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:21:09 INFO - '--tppagecycles', 19:21:09 INFO - '7'], 19:21:09 INFO - 'tests': ['tsvgm']}}, 19:21:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:21:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:21:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:21:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:21:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['damp', 'tps']}, 19:21:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'tests': ['damp', 'tps']}, 19:21:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['dromaeo_dom']}, 19:21:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:21:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['basic_compositor_video']}, 19:21:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:21:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other-e10s': {'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tsvgx', 19:21:09 INFO - 'tsvgr_opacity', 19:21:09 INFO - 'tart', 19:21:09 INFO - 'tscrollx', 19:21:09 INFO - 'cart']}, 19:21:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:21:09 INFO - 'tsvgr_opacity', 19:21:09 INFO - 'tart', 19:21:09 INFO - 'tscrollx', 19:21:09 INFO - 'cart']}, 19:21:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tp5o']}, 19:21:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'tests': ['tp5o']}, 19:21:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s', 19:21:09 INFO - '--xperf_path', 19:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:09 INFO - 'tests': ['tp5n']}, 19:21:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--xperf_path', 19:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:09 INFO - 'tests': ['tp5n']}}, 19:21:09 INFO - 'talos.zip': {'path': '', 19:21:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:21:09 INFO - Running post-action listener: _resource_record_post_action 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.989872Z] Finished populate-webroot step (success) 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.990004Z] Running create-virtualenv step. 19:21:09 INFO - Running pre-action listener: _resource_record_pre_action 19:21:09 INFO - Running main action method: create_virtualenv 19:21:09 INFO - Creating virtualenv /builds/slave/test/build/venv 19:21:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:21:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:21:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:21:10 INFO - Using real prefix '/tools/python27' 19:21:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:21:10 INFO - Installing distribute.............................................................................................................................................................................................done. 19:21:13 INFO - Installing pip.................done. 19:21:13 INFO - Return code: 0 19:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:21:13 INFO - Reading from file tmpfile_stdout 19:21:13 INFO - Output received: 19:21:13 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:21:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:13 INFO - 'HOME': '/Users/cltbld', 19:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:13 INFO - 'LOGNAME': 'cltbld', 19:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:13 INFO - 'MOZ_NO_REMOTE': '1', 19:21:13 INFO - 'NO_EM_RESTART': '1', 19:21:13 INFO - 'PAGER': '/bin/cat', 19:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:13 INFO - 'PWD': '/builds/slave/test', 19:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:13 INFO - 'SHELL': '/bin/bash', 19:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:13 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:13 INFO - 'USER': 'cltbld', 19:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:13 INFO - 'XPC_FLAGS': '0x0', 19:21:13 INFO - 'XPC_SERVICE_NAME': '0', 19:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:21:14 INFO - Cleaning up... 19:21:14 INFO - Return code: 0 19:21:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:14 INFO - 'HOME': '/Users/cltbld', 19:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:14 INFO - 'LOGNAME': 'cltbld', 19:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:14 INFO - 'MOZ_NO_REMOTE': '1', 19:21:14 INFO - 'NO_EM_RESTART': '1', 19:21:14 INFO - 'PAGER': '/bin/cat', 19:21:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:14 INFO - 'PWD': '/builds/slave/test', 19:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:14 INFO - 'SHELL': '/bin/bash', 19:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:14 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:14 INFO - 'USER': 'cltbld', 19:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:14 INFO - 'XPC_FLAGS': '0x0', 19:21:14 INFO - 'XPC_SERVICE_NAME': '0', 19:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:14 INFO - Downloading/unpacking psutil>=3.1.1 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:21:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:21:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:21:18 INFO - Installing collected packages: psutil 19:21:18 INFO - Running setup.py install for psutil 19:21:18 INFO - building 'psutil._psutil_osx' extension 19:21:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:21:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:21:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:21:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:21:19 INFO - building 'psutil._psutil_posix' extension 19:21:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:21:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:21:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:21:19 INFO - ^ 19:21:19 INFO - 1 warning generated. 19:21:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:21:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:21:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:21:19 INFO - Successfully installed psutil 19:21:19 INFO - Cleaning up... 19:21:19 INFO - Return code: 0 19:21:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:19 INFO - 'HOME': '/Users/cltbld', 19:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:19 INFO - 'LOGNAME': 'cltbld', 19:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:19 INFO - 'MOZ_NO_REMOTE': '1', 19:21:19 INFO - 'NO_EM_RESTART': '1', 19:21:19 INFO - 'PAGER': '/bin/cat', 19:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:19 INFO - 'PWD': '/builds/slave/test', 19:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:19 INFO - 'SHELL': '/bin/bash', 19:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:19 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:19 INFO - 'USER': 'cltbld', 19:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:19 INFO - 'XPC_FLAGS': '0x0', 19:21:19 INFO - 'XPC_SERVICE_NAME': '0', 19:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:21:22 INFO - Installing collected packages: mozsystemmonitor 19:21:22 INFO - Running setup.py install for mozsystemmonitor 19:21:22 INFO - Successfully installed mozsystemmonitor 19:21:22 INFO - Cleaning up... 19:21:22 INFO - Return code: 0 19:21:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:22 INFO - 'HOME': '/Users/cltbld', 19:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:22 INFO - 'LOGNAME': 'cltbld', 19:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:22 INFO - 'MOZ_NO_REMOTE': '1', 19:21:22 INFO - 'NO_EM_RESTART': '1', 19:21:22 INFO - 'PAGER': '/bin/cat', 19:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:22 INFO - 'PWD': '/builds/slave/test', 19:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:22 INFO - 'SHELL': '/bin/bash', 19:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:22 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:22 INFO - 'USER': 'cltbld', 19:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:22 INFO - 'XPC_FLAGS': '0x0', 19:21:22 INFO - 'XPC_SERVICE_NAME': '0', 19:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:23 INFO - Downloading/unpacking jsonschema==2.5.1 19:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:21:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:26 INFO - Downloading functools32-3.2.3-2.tar.gz 19:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:21:26 INFO - warning: no files found matching '*.txt' 19:21:26 INFO - no previously-included directories found matching 'build' 19:21:26 INFO - no previously-included directories found matching 'dist' 19:21:26 INFO - no previously-included directories found matching '.git*' 19:21:26 INFO - Installing collected packages: jsonschema, functools32 19:21:26 INFO - Running setup.py install for functools32 19:21:26 INFO - warning: no files found matching '*.txt' 19:21:26 INFO - no previously-included directories found matching 'build' 19:21:26 INFO - no previously-included directories found matching 'dist' 19:21:26 INFO - no previously-included directories found matching '.git*' 19:21:26 INFO - Successfully installed jsonschema functools32 19:21:26 INFO - Cleaning up... 19:21:26 INFO - Return code: 0 19:21:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:21:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:26 INFO - 'HOME': '/Users/cltbld', 19:21:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:26 INFO - 'LOGNAME': 'cltbld', 19:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:26 INFO - 'MOZ_NO_REMOTE': '1', 19:21:26 INFO - 'NO_EM_RESTART': '1', 19:21:26 INFO - 'PAGER': '/bin/cat', 19:21:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:26 INFO - 'PWD': '/builds/slave/test', 19:21:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:26 INFO - 'SHELL': '/bin/bash', 19:21:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:26 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:26 INFO - 'USER': 'cltbld', 19:21:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:26 INFO - 'XPC_FLAGS': '0x0', 19:21:26 INFO - 'XPC_SERVICE_NAME': '0', 19:21:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:21:26 INFO - Cleaning up... 19:21:26 INFO - Return code: 0 19:21:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:21:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:26 INFO - 'HOME': '/Users/cltbld', 19:21:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:26 INFO - 'LOGNAME': 'cltbld', 19:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:26 INFO - 'MOZ_NO_REMOTE': '1', 19:21:26 INFO - 'NO_EM_RESTART': '1', 19:21:26 INFO - 'PAGER': '/bin/cat', 19:21:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:26 INFO - 'PWD': '/builds/slave/test', 19:21:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:26 INFO - 'SHELL': '/bin/bash', 19:21:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:26 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:26 INFO - 'USER': 'cltbld', 19:21:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:26 INFO - 'XPC_FLAGS': '0x0', 19:21:26 INFO - 'XPC_SERVICE_NAME': '0', 19:21:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:26 INFO - Downloading/unpacking blobuploader==1.2.4 19:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:29 INFO - Downloading blobuploader-1.2.4.tar.gz 19:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:21:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:21:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:30 INFO - Downloading docopt-0.6.1.tar.gz 19:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:21:30 INFO - Installing collected packages: blobuploader, requests, docopt 19:21:30 INFO - Running setup.py install for blobuploader 19:21:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:21:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:21:30 INFO - Running setup.py install for requests 19:21:31 INFO - Running setup.py install for docopt 19:21:31 INFO - Successfully installed blobuploader requests docopt 19:21:31 INFO - Cleaning up... 19:21:31 INFO - Return code: 0 19:21:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:31 INFO - 'HOME': '/Users/cltbld', 19:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:31 INFO - 'LOGNAME': 'cltbld', 19:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:31 INFO - 'MOZ_NO_REMOTE': '1', 19:21:31 INFO - 'NO_EM_RESTART': '1', 19:21:31 INFO - 'PAGER': '/bin/cat', 19:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:31 INFO - 'PWD': '/builds/slave/test', 19:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:31 INFO - 'SHELL': '/bin/bash', 19:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:31 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:31 INFO - 'USER': 'cltbld', 19:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:31 INFO - 'XPC_FLAGS': '0x0', 19:21:31 INFO - 'XPC_SERVICE_NAME': '0', 19:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:21:31 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ixnWek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:21:31 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-WDePqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:21:31 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-y0irbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zP9dqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZCou1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-3zAgpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-43ecNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-bs8sAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-m45DWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-LLFheS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Ox85h2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-020YJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:21:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zrwZq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:21:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-MuXOXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-QC99iH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:21:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tT016O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:21:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jROtwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:21:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:21:33 INFO - Running setup.py install for manifestparser 19:21:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:21:33 INFO - Running setup.py install for mozcrash 19:21:33 INFO - Running setup.py install for mozdebug 19:21:33 INFO - Running setup.py install for mozdevice 19:21:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozfile 19:21:34 INFO - Running setup.py install for mozhttpd 19:21:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozinfo 19:21:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozInstall 19:21:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozleak 19:21:34 INFO - Running setup.py install for mozlog 19:21:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for moznetwork 19:21:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Running setup.py install for mozprocess 19:21:35 INFO - Running setup.py install for mozprofile 19:21:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Running setup.py install for mozrunner 19:21:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Running setup.py install for mozscreenshot 19:21:35 INFO - Running setup.py install for moztest 19:21:35 INFO - Running setup.py install for mozversion 19:21:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:21:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:21:36 INFO - Cleaning up... 19:21:36 INFO - Return code: 0 19:21:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:21:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:36 INFO - 'HOME': '/Users/cltbld', 19:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:36 INFO - 'LOGNAME': 'cltbld', 19:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:36 INFO - 'MOZ_NO_REMOTE': '1', 19:21:36 INFO - 'NO_EM_RESTART': '1', 19:21:36 INFO - 'PAGER': '/bin/cat', 19:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:36 INFO - 'PWD': '/builds/slave/test', 19:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:36 INFO - 'SHELL': '/bin/bash', 19:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:36 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:36 INFO - 'USER': 'cltbld', 19:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:36 INFO - 'XPC_FLAGS': '0x0', 19:21:36 INFO - 'XPC_SERVICE_NAME': '0', 19:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZNzogC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-aY2yPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Jfbb3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-bDz8dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Z3YnrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-y8TLF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:21:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-LcaZPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-DzKoUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-sWEBcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-do5olM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-oH_S2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ndJBuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zLjezI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-M3_aLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-y9SYyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:21:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-vgL6ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:21:38 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-6f1Pwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:21:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:40 INFO - Downloading blessings-1.6.tar.gz 19:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:21:40 INFO - Installing collected packages: blessings 19:21:40 INFO - Running setup.py install for blessings 19:21:40 INFO - Successfully installed blessings 19:21:40 INFO - Cleaning up... 19:21:40 INFO - Return code: 0 19:21:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:21:41 INFO - Reading from file tmpfile_stdout 19:21:41 INFO - Current package versions: 19:21:41 INFO - blessings == 1.6 19:21:41 INFO - blobuploader == 1.2.4 19:21:41 INFO - docopt == 0.6.1 19:21:41 INFO - functools32 == 3.2.3-2 19:21:41 INFO - jsonschema == 2.5.1 19:21:41 INFO - manifestparser == 1.1 19:21:41 INFO - mozInstall == 1.12 19:21:41 INFO - mozcrash == 0.17 19:21:41 INFO - mozdebug == 0.1 19:21:41 INFO - mozdevice == 0.48 19:21:41 INFO - mozfile == 1.2 19:21:41 INFO - mozhttpd == 0.7 19:21:41 INFO - mozinfo == 0.9 19:21:41 INFO - mozleak == 0.1 19:21:41 INFO - mozlog == 3.4 19:21:41 INFO - moznetwork == 0.27 19:21:41 INFO - mozprocess == 0.23 19:21:41 INFO - mozprofile == 0.28 19:21:41 INFO - mozrunner == 6.12 19:21:41 INFO - mozscreenshot == 0.1 19:21:41 INFO - mozsystemmonitor == 0.3 19:21:41 INFO - moztest == 0.7 19:21:41 INFO - mozversion == 1.4 19:21:41 INFO - psutil == 3.1.1 19:21:41 INFO - requests == 1.2.3 19:21:41 INFO - wsgiref == 0.1.2 19:21:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:41 INFO - 'HOME': '/Users/cltbld', 19:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:41 INFO - 'LOGNAME': 'cltbld', 19:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:41 INFO - 'MOZ_NO_REMOTE': '1', 19:21:41 INFO - 'NO_EM_RESTART': '1', 19:21:41 INFO - 'PAGER': '/bin/cat', 19:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:41 INFO - 'PWD': '/builds/slave/test', 19:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:41 INFO - 'SHELL': '/bin/bash', 19:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:41 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:41 INFO - 'USER': 'cltbld', 19:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:41 INFO - 'XPC_FLAGS': '0x0', 19:21:41 INFO - 'XPC_SERVICE_NAME': '0', 19:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:21:41 INFO - Cleaning up... 19:21:41 INFO - Return code: 0 19:21:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda19d74e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, '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.WDxdMeuLto/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.Tn5r6USOeK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:21:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:41 INFO - 'HOME': '/Users/cltbld', 19:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:41 INFO - 'LOGNAME': 'cltbld', 19:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:41 INFO - 'MOZ_NO_REMOTE': '1', 19:21:41 INFO - 'NO_EM_RESTART': '1', 19:21:41 INFO - 'PAGER': '/bin/cat', 19:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:41 INFO - 'PWD': '/builds/slave/test', 19:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:41 INFO - 'SHELL': '/bin/bash', 19:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:21:41 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:41 INFO - 'USER': 'cltbld', 19:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:41 INFO - 'XPC_FLAGS': '0x0', 19:21:41 INFO - 'XPC_SERVICE_NAME': '0', 19:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:21:41 INFO - Cleaning up... 19:21:41 INFO - Return code: 0 19:21:41 INFO - Running post-action listener: _resource_record_post_action 19:21:41 INFO - Running post-action listener: _start_resource_monitoring 19:21:41 INFO - Starting resource monitoring. 19:21:41 INFO - [mozharness: 2016-10-05 02:21:41.785601Z] Finished create-virtualenv step (success) 19:21:41 INFO - [mozharness: 2016-10-05 02:21:41.786627Z] Running install step. 19:21:41 INFO - Running pre-action listener: _resource_record_pre_action 19:21:41 INFO - Running main action method: install 19:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:21:42 INFO - Reading from file tmpfile_stdout 19:21:42 INFO - Detecting whether we're running mozinstall >=1.0... 19:21:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:21:42 INFO - Reading from file tmpfile_stdout 19:21:42 INFO - Output received: 19:21:42 INFO - Usage: mozinstall [options] installer 19:21:42 INFO - Options: 19:21:42 INFO - -h, --help show this help message and exit 19:21:42 INFO - -d DEST, --destination=DEST 19:21:42 INFO - Directory to install application into. [default: 19:21:42 INFO - "/builds/slave/test"] 19:21:42 INFO - --app=APP Application being installed. [default: firefox] 19:21:42 INFO - mkdir: /builds/slave/test/build/application 19:21:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:22:16 INFO - Reading from file tmpfile_stdout 19:22:16 INFO - Output received: 19:22:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:22:16 INFO - Running post-action listener: _resource_record_post_action 19:22:16 INFO - [mozharness: 2016-10-05 02:22:16.039443Z] Finished install step (success) 19:22:16 INFO - [mozharness: 2016-10-05 02:22:16.039544Z] Running run-tests step. 19:22:16 INFO - Running pre-action listener: _resource_record_pre_action 19:22:16 INFO - Running main action method: run_tests 19:22:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:22:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:22:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:22:16 INFO - 2016-10-04 19:22:16.053 screenresolution[1644:8079] starting screenresolution argv=screenresolution get 19:22:16 INFO - 2016-10-04 19:22:16.056 screenresolution[1644:8079] Display 0: 1600x1200x32@60 19:22:16 INFO - 2016-10-04 19:22:16.062 screenresolution[1645:8081] starting screenresolution argv=screenresolution list 19:22:16 INFO - Available Modes on Display 0 19:22:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:22:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:22:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:22:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:22:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:22:16 INFO - Intel Iris: 19:22:16 INFO - Chipset Model: Intel Iris 19:22:16 INFO - Type: GPU 19:22:16 INFO - Bus: Built-In 19:22:16 INFO - VRAM (Dynamic, Max): 1536 MB 19:22:16 INFO - Vendor: Intel (0x8086) 19:22:16 INFO - Device ID: 0x0a2e 19:22:16 INFO - Revision ID: 0x0009 19:22:16 INFO - Displays: 19:22:16 INFO - Display: 19:22:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:22:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:22:16 INFO - Display Serial Number: 1600x1200 60 19:22:16 INFO - Main Display: Yes 19:22:16 INFO - Mirror: Off 19:22:16 INFO - Online: Yes 19:22:16 INFO - Rotation: Supported 19:22:16 INFO - Return code: 0 19:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:22:16 INFO - Python 2.7.3 19:22:16 INFO - Return code: 0 19:22:16 INFO - grabbing minidump binary from tooltool 19:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:16 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 0x103895d30>, '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 0x1036bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:22:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:22:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:22:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:22:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:22:16 INFO - Return code: 0 19:22:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:22:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:22:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:22:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:22:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDxdMeuLto/Render', 19:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:16 INFO - 'HOME': '/Users/cltbld', 19:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:16 INFO - 'LOGNAME': 'cltbld', 19:22:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:22:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:16 INFO - 'MOZ_NO_REMOTE': '1', 19:22:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:22:16 INFO - 'NO_EM_RESTART': '1', 19:22:16 INFO - 'PAGER': '/bin/cat', 19:22:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:16 INFO - 'PWD': '/builds/slave/test', 19:22:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:16 INFO - 'SHELL': '/bin/bash', 19:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tn5r6USOeK/Listeners', 19:22:16 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 19:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:16 INFO - 'USER': 'cltbld', 19:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:16 INFO - 'XPC_FLAGS': '0x0', 19:22:16 INFO - 'XPC_SERVICE_NAME': '0', 19:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:22:16 INFO - mozversion application_buildid: 20161004172100 19:22:16 INFO - mozversion application_changeset: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 19:22:16 INFO - mozversion application_display_name: Nightly 19:22:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:22:16 INFO - mozversion application_name: Firefox 19:22:16 INFO - mozversion application_remotingname: firefox 19:22:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:22:16 INFO - mozversion application_vendor: Mozilla 19:22:16 INFO - mozversion application_version: 52.0a1 19:22:16 INFO - mozversion platform_buildid: 20161004172100 19:22:16 INFO - mozversion platform_changeset: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 19:22:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:22:16 INFO - mozversion platform_version: 52.0a1 19:22:16 INFO - using testdate: 1475634136 19:22:16 INFO - actual date: 1475634136 19:22:16 INFO - starting webserver on 'localhost:49274' 19:22:16 INFO - SUITE-START | Running 2 tests 19:22:16 INFO - TEST-START | dromaeo_css 19:22:16 INFO - operating with platform_type : mac_ 19:22:16 INFO - Initialising browser for dromaeo_css test... 19:22:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmph07jSC/profile http://localhost:49274/getInfo.html) 19:22:19 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 19:22:19 INFO - PROCESS | 1655 | colorDepth:24 19:22:19 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 19:22:19 INFO - PROCESS | 1655 | __metrics 19:22:19 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:22:19 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:22:20 INFO - TEST-INFO | 1655: exit 0 19:22:20 INFO - Browser initialized. 19:22:20 INFO - Running cycle 1/1 for dromaeo_css test... 19:22:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmph07jSC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:32 INFO - PROCESS | 1658 | RSS: Main: 244584448 19:22:32 INFO - PROCESS | 1658 | 19:23:23 INFO - PROCESS | 1658 | 1475634203713 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:25:36 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 19:25:37 INFO - PROCESS | 1658 | RSS: Main: 372490240 19:25:37 INFO - PROCESS | 1658 | 19:28:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 19:28:41 INFO - PROCESS | 1658 | RSS: Main: 409239552 19:28:41 INFO - PROCESS | 1658 | 19:31:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 19:31:44 INFO - PROCESS | 1658 | RSS: Main: 336625664 19:31:44 INFO - PROCESS | 1658 | 19:34:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 19:34:47 INFO - PROCESS | 1658 | RSS: Main: 344805376 19:34:47 INFO - PROCESS | 1658 | 19:37:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 19:37:51 INFO - PROCESS | 1658 | RSS: Main: 328007680 19:37:51 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 19:40:55 INFO - PROCESS | 1658 | RSS: Main: 392921088 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | __start_tp_report 19:40:55 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 19:40:55 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 19:40:55 INFO - PROCESS | 1658 | |i|pagename|runs| 19:40:55 INFO - PROCESS | 1658 | |0;dojo.html;2896.768258539317;3283.8358082095906;3322.3355328934203;3345.3978283908887;3368.730501559874;5580.888382232353;5623.268975033245;5653.98228318903;5655.943440565593;5702.714864256791;5652.09566469365;5663.575231857607;5716.028275193216;5717.085266357383;5833.154192642064;5136.768845401947;5187.6887386756725;5227.059129356724;5227.581793456533;5234.528892399683;5150.484951504857;5250.081235783741;5256.080185967459;5261.710605916673;5274.4198138204765;5257.027449921758;5261.289725886994;5271.393789714174;5276.762545685434;5303.973480132594;5103.157978933508;5180.507851754077;5183.896322073575;5246.186841039606;5262.447443018466;5123.974380128076;5165.302684137669;5166.328377310963;5172.094883395391;5177.767000484987;5502.339719233678;5560.638558493685;5591.832245032656;5631.662100273997;5643.6049476536655;6109.2224806663635;6189.442950134465;6192.411720886508;6206.007038873759;6208.192934918432;6275.372462753734;6286;6287.811365658992;6296.150019747367;6297.307296197415;5617.157426386075;5692.544596432275;5725.198472213893;5742.971285143548;5746.281714785652;6251.062340648939;6289.0252010938375;6290.622562646256;6307.905381419282;6321.304656487782;6079.75680972765;6096.116063170815;6098.969505152446;6099.390060993954;6101.816945491642;6126.295476020226;6240.095186197976;6245.594036387621;6245.843853903598;6253.343398943137;6057.394260573996;6090.299615544269;6104.420080092473;6105.32841387447;6123.295820980555;6092.130708583294;6125.265974675666;6129.172561704113;6142.600730952474;6156.29202641693;4902.926456103161;4903.068417000758;4912.754362281871;4931.580815630711;4959.70241785494;4345.109252603209;4373.781310934504;4390.100029493946;4395.362672412546;4438.356438316426;5603.271574695264;5657.066584013592;5713.22871412354;5716.085426331767;5725.971370143123;6170.50635949123;6204.751809927552;6247.468965137923;6247.625142491374;6284.214473190851;5699.686517241508;5720.599558030898;5731.570132240098;5746.109353050284;5761.078227483583;5366.302380690485;5377.166539186433;5394.541463975527;5401.162819762943;5402.6758394496455;4902.975485122552;6216.844578885564;6224.097505861715;6274.686265686789;6280.905786413299;6217.036359364306;6217.875642487215;6223.066540019034;6230.59501132425;6238.438540531374;6277.874442511215;6286.591371560834;6301.6534090625455;6306.873862522815;6307.400796924285;4872.244802055687;6054.606450580698;6071.483923866521;6107.765388666889;6143.201383820075;6200.627962322275;6281.371862813682;6291.842703932438;6299.307076221704;6303.558750887394;5844.707764611838;6130.172426722335;6130.386961303834;6132.38676132401;6148.292946311231;5839.854003649943;5969.582129250911;6008.1588577598295;6110.57225994194;6112.388761123852;5862.589618726648;5938;5942.61373010753;5952.702364881825;5972.432618901197;6041.577089603686;6117.632942023315;6126.724297406538;6129.141920131275;6140.416660417253;5706.343770466284;5827.388124246893;5879.265091863517;5896.941030589639;5911.822645320647 19:40:55 INFO - PROCESS | 1658 | |1;ext.html;518.5359103602276;529.2141170362012;535.8097875254284;536.0458384076343;556.1518684006886;27810.443791124166;40506.987325316884;41920.790396047974;41941.7902910485;44142.77928610353;43135.784321078354;43435.565644343544;43436.34845477313;43470.695879123676;43542.78228608853;8635.568221588926;9490.857637135432;9679.322447428684;9684.934657187703;9701.951490242538;8737.475751454933;11837.171398002143;12196.817047744247;12205.694857628541;12413.813792793115;12105.455254513568;12179.086568507353;12189.26864388135;12207.267563946147;12207.901288883999;12108.81836772449;12232.510699572007;12238.694032649166;12248.755024899541;12256.693582660415;11807.704807379885;11896.702582435422;11898.821517677155;11908;11927.821082683766;40049.79975100135;40322;40420.79789601063;40472;40509.79745101256;2726.4819684260124;3340.415427300237;3439.1402149462633;3495.615482296945;3691.8523259069875;23740.525189496115;30156.24609384783;30768;31052.913148039937;31940.680593194236;3128.7809853310278;3148.889788857402;3173.4763763978917;3192.944959900936;3281.9343613127844;468985;469608;469745;470600;482792;60873;61222.081668775005;61226;61256.081158782654;61276;63549;63967.680161598895;64215;64431;64484.71030579362;15865.444709435114;16075.437359692354;16081.597960050858;16120.193990300439;16145.112018839076;11240.718982025515;11468.713282167846;11483;12482.625521234377;12556.937215313865;15438.228088595526;15563.832712546604;16907.07011114376;17249.310027599007;17344.739828902577;5561;6670.533062685566;6727.730890764315;6758.222804377559;6818.897716534256;828.5939889454124;945.0785484152896;967.1199208720171;984.1978787288338;1084.18940369583;28815.279618009718;29315.413691726048;29646.703532964395;30271.394572108875;30756.846215769227;1018.6587493189717;1031.2368847053276;1039.3867618105355;1067.199600299775;1081.7457897394106;14775.556733298019;15420.768688469681;15487.225638718019;15589.922050389676;15801.070503710995;620023;626740;627861;628102;630286;57052.42947570554;57175.14237286444;57262;57367;57378.7131064342;1095.5508241621058;1208.7522057978094;1253.7429826885284;1354.4514471639002;1373.2996171952177;26042;30706.925257615876;31618.367632647678;32127.51808722918;32258.35483290368;54824.45175548353;55577;55593.72203138958;55653;55758.721206395334;415555;420650;420948;421337;424279;31550.684493154775;34683.65316346804;35558.644413555536;39431.21137577175;40030.59969400269;39800.60199397969;47526.76236618933;47980.520194799;47985;49074.509254906996;38862;39244;39346.803265983486;40557.797211014935;40914.590854091075;38135;38555.036124097125;38942.221155577296;39471.80264098776;39694.80152599219;38023;38154.61845381511;38359;38983;39283.80358098191;30317;34203.199896035825;34918.65081349154;34952.82523587366;34992.47511287282;25198;25372.492550148523;25441.236762897144;25547;26152.476950460503 19:40:55 INFO - PROCESS | 1658 | |2;jquery.html;332.38176990797115;351.6044449993757;356.7342329964177;377.4319648928362;384.3830651803855;14981.5505534834;15179.2410379481;16259.51221463355;20788.376348709524;20896.791032089674;19461;19988.30040948567;20611.587768244626;20846.68729969052;21099.789002109974;5954.910676339848;6158.199434073565;6197.442230199281;6211.130441738161;6212.9068063979075;5108.565771909392;6801.795946121624;6874.450043996493;6955.165380154389;6968.547044442121;6597.538172327915;6737.3262673732725;6821.420179284767;6881.277465866087;6909.516333856632;6713;6813.114295141625;6854.143232095988;6859.485538584576;6868.278830722802;5703.885922281531;5894.410558944114;5896.145058966469;5900.822975310747;5901.793437229677;26847.32881677954;26867.462650746875;26885.0590229343;26931.017761714145;26989;2425.6967879015124;2505.148249595128;2620.737926207383;3159.921001974969;3161.525771134325;9638.469884156368;10286.971302869653;10325.741856453573;10370.066693997502;10415.479226038668;1754.9385771498064;1791.4983804534752;1816.8455681267108;1840.8067152949016;1841.2542920117373;96953.51523242409;116418.41790790991;120302;121854;122306;10148.680671512657;10598.523066462029;11477.36874471912;11497.827532587018;11637.185397022296;14341.78487322691;14365.64085897861;14472.348744306531;14491.637709057148;14506.85493145055;8362.916370836336;8372.03721572015;8389.790255243546;8466.872996905051;8568.52873091986;8348.582570871553;8382.329413646894;8383.11977242393;8531.274841638531;8533.975922889293;4271.974726065722;4406.25093734066;4495.965927836616;4505.7071290366675;4558.316252562142;1670.3151707799743;1918.827305542508;1919.433767038727;1921.5868588253313;1924.5284905198316;810.7284059839902;811.5009269299456;899.2581120575551;1005.1605561821848;1042.5829668132808;19758.814471131776;21500.032498537603;21586.19182870871;21639.891800541212;21652.102621528276;695.5027155583681;882.2104216725994;926.9721908342625;939.9295052871062;957.1051067252002;10652.520636571373;11148.386838723785;11210.719732006764;11247.550097996153;11254.49354779037;503492;525984;576566;578473;599672;92479.53760231155;132011.01983470254;136671;142264;143132;871.808202195516;946.6071580294101;971.7813491964251;990.8315586350337;997.1175509673845;18374.448766537025;19236.71144932855;19254.7111793326;19264;19277.939713315904;36600.81699591485;52214.69463263449;52967.82193068428;53885.730571346896;55597.722011389684;77284;78147.6092619556;78842;78941.81587276081;78987;7750.961245193738;7949;8063.113057563781;8161.224683655043;8207.522645923791;12120.575779847662;12741.617751467471;12829.55096571653;13138.80291795643;13305.268210248529;9069.591868366071;10763.515641796295;11101.722456938642;11195.496202670736;11214.831777523506;10236.436995965294;10741.409222492837;10770.407627580558;11300.773984520429;11407.543698251977;10989.835152472559;11341.943290283496;11616.070714343;11716.765664686829;11806.409679516162;7977.162397948132;7985.8802117969435;8071.273585377109;8115.553644549606;8190.221928916743;9608.75978100553;10895.455227238448;11235.887641123483;11318.434078295888;11334.263272887236 19:40:55 INFO - PROCESS | 1658 | |3;mootools.html;1439.4098419647946;1487.9584290996302;1667.5664327274912;1837.6232872261187;1893.9337123200692;8541.316694664427;9043.412178208411;9049.41178823376;9065.32010099242;9066.138716821899;7975.40184486163;8292.336613070955;8296.37777166712;8669.783255418617;8909.198172164504;2360.0441821062454;2387.8209134314907;2396.1613435297595;2396.5087157132834;2397.0531640002187;2354.740978492373;2358.1157066100213;2400.411899084727;2400.5558971590226;2408.590539608262;2377.3105799318178;2393.509330587234;2398.8920498577518;2399.1363109280646;2409.168836751318;2357.1749887539395;2362.9763702363102;2365.5860224460653;2373.062640257106;2389.307100940725;2355.234548771656;2363.5745565798147;2371.1226846066893;2375.7743014413604;2380.357303528038;8951.865722014176;8974.641014359418;9077.36458447909;9093.545322733902;9102.089791020911;5265.2628631991165;5307.203919412081;5431.646942948696;5431.809886653988;5444.700541470217;3628.546431696038;3648.6168952259895;3714.4799728038097;3723.0134014486184;3726.6832319252894;1068.6633710381284;1068.9144868410508;1078.3691540448847;1096.627146770102;1100.862392200975;12006.459709313101;12099.818502722466;12120.030397568173;12123.393830308538;12195.93902030493;4485.529019452975;4604.263317869125;4759.881002974884;4793.699024468275;4823.807047718122;10057.597696092074;10086.344387614781;10105.29262951601;10113.393196408091;10147.441890696082;5324.893502129936;5327.334083239595;5327.413984461706;5328.387235467971;5333.973330133324;4366.847160349373;4398.516163222043;4516.548345165523;4734.95265047352;4861.124997500484;3318.7510936679846;3832.693384529231;3872.845086196577;3992.4610177626228;3992.7205095643026;1255.918365306252;1281.4810001949224;1282.2883299768719;1288.7873500872438;1289.3875409180753;809.4293523066342;850.4429598612918;878.6819770344483;927.6845872403416;944.2587568758491;5809.912851307234;5840.561957853093;5974.671393073423;6067.848303792352;6288.151099601587;738.1732459645232;880.1418616848649;908.7728067983004;950.1780959470009;1006.854006169116;3650.9634903651154;3916.1580260244186;4034.3141665916864;4034.495688038995;4080.2859499587453;10384.948075259575;10661.520231589597;10887.455627218607;10958.452077396258;11049.33703977764;11002.174836887265;11015.559377624806;11018.669439916815;11020.50407731654;11041;8002.279794818494;8351.749447516584;8392.538410387488;8409.369297302605;8420.326373890073;5694.37710252576;5733.885322293448;5803.82588522345;5806.332271788799;5891.293044834647;9975.20198384113;10235.7441063974;10306.227032972707;10318.329308594917;10321.90342897143;9828.754281142743;10207.387556746618;10291.691249262534;10376.221783366127;10451.268411211142;6567.9671601641685;6791.966040169767;6961.443084553122;7106.822329441805;7178.964105179441;7975.082865470546;8243.010838699394;8551.444156130081;8555.700550480704;8609.870851937101;7387.298206670358;7530.134034586093;7569.545827250383;7627.694892204471;7652.73215437457;7399.667014984231;7428.591427471325;7485.251474852471;7489.962550187214;7604.8479030417975;6914.412274956685;7078.646067696697;7080.787576372717;7085.610291434021;7099.893501597377;4914.680545764514;4985.875353116056;5042.134410268224;5069.746512674278;5146.562542183956;4952.752362381939;5010.899782004267;5130.8973820524125;5159.174532075001;5235.3717553893775 19:40:55 INFO - PROCESS | 1658 | |4;prototype.html;302.750231059376;304.2667172115204;311.4860480207658;317.393777884241;318.33626887938647;4588.54114588541;5205.713685747282;5423.810166644168;5439.129739241722;5452.127659574464;4795.520447955203;5166.276721259027;5254.369475662917;5290.841274761754;5364.7317634118335;3432.7425443091806;3986.8803935881824;4134.7312424692445;4135.524414692319;4172.603602657743;3618.9095272618133;3937;4112.938305925413;4170.18596910345;4181.205570941526;3481.346360478765;3527.6472352764645;3925.9018524536973;4000.219957108365;4017.5781542938007;3346.548215990859;4012.936571808473;4035.8609936733283;4056.492938382702;4059.289624315748;3041.9847900760574;3562.109472631842;3576.141725985771;3582.7671201371827;3595.442706380489;4556.066006468666;4619.221599684112;5196.558292545138;5256.1852912798195;5334.59990500714;4210.789460526992;4684.391029166187;4685.742284174369;4700.365450664151;4704;5004.674696144739;5619.662820230799;5649.971750141264;5667.8583035424035;5717.656940583537;3307.884224052171;3368.309496553201;3588.0312315674623;3609.06164397256;3822.9617703822883;5872.089826076922;5915.704214789243;5921.378255283219;5922.763089476416;5935.287765468128;3904.590018048064;3907.5897030811925;3931.1327119617954;3932.803359831997;3971.8411263549133;3303.3888730584918;3733.086320151014;3886.9505233586774;3948.6051394860283;3953.723239373216;4345.869623911288;4548.1807277088765;4596.6322694185055;4620.861374158781;4665.5567721066445;5110.897782044338;5440.911817636488;5675.744591493392;5725.427457254241;5754.6547207167705;1181.5864447443325;1261.2810697902107;1334.2128144394853;1352.9729405411836;1361.339750221142;809.4091313341294;811.5779794506941;816.1145157504055;824.9440715883678;827.3546633625782;3801.315763162602;3926.6858651307825;4443.466783985942;4450.35469856869;4471.3516540102155;4300.935485967713;4647.837325693585;4820.831270905502;4825.5898248648555;4884.876478409986;4874.707517548958;5095.305903220135;5203.791848326025;5207.073382848371;5229.764660590206;2571.922842314696;2915.8482399452523;3011.7741169412384;3051.794541156231;3066.803946460882;5025.84922452327;5478.342598888079;5542.418046105101;6027.397260273938;6050.848728781815;4774.665773395898;5925.377835327315;5957.434043765836;6023.975924092914;6055.515558755289;5060.797568097235;5828.766849326064;6025;6026.728797204224;6051.969740151271;4949.9063085571515;5440.972795135999;5737.741801618854;5833.416658334302;5866.67733274674;5269.683818970874;5663.207150998781;5702.287214098238;5731.713414329351;5757.769689212385;5150.381954165524;5906.379830117776;6034.758609655741;6042.214512113573;6044.335123136363;3879.4956655634587;3909.6481316680497;3918.843246270117;3921.3922741377805;3927.4108883666536;4834.444038935427;5848.736806843787;5873.94411955812;5883.235179426647;5934.388868897671;4689.835855745033;5427.375851776938;5714.79998200061;5731.484166425042;5773.566982476415;4411.9631886506;4583.106294272585;4731.834385796481;4953.653244272867;5602.831915042555;4852.8544143675745;5292.576593872558;5571.7771289149605;5608.691521966331;5610.49505544503;5365.463453654604;5655.575831812548;5675.432456754456;5707.230758464889;5757.596968212185;4239.554846741171;5193.714345710871;5287.471252874681;5559.805406810742;5560.4161563035295 19:40:55 INFO - PROCESS | 1658 | |5;yui.html;673.9831504212398;706.9893951590727;714.2036629158488;747.7233423633256;851.9616617252223;11523.19337646364;13437.596872093829;13459.663508412295;13603;13655.590332290023;13359.465621375133;13932.303384830744;13995.160290382584;14163.787543186834;14211.573652790406;722.9132504099503;874.9300055995506;877.0659247900996;879.3141349747175;879.5118709116438;691.754427178352;714.474860977184;719.8776208044645;850.0988951711175;870.651739304277;911.1389736698823;912.8219997100568;913.9443942246716;914.7484441778531;916.5188276155005;906.863970404438;908.4594666173682;908.8046070094895;909.0273407454027;909.595230122599;532.8641196494908;550.6090675620275;551.0796969061677;552.5165480204821;679.9694013769392;12459.377031148406;13893.513727019506;14354.712905741826;14424.71150576993;14733.92633036828;14151.504697335648;14384.928075359661;14530.782038269434;14533.927330363385;14592.489262875748;11579.594714184963;11676.613782003295;11681.415929203591;12148.87851121495;12268.877311226863;3726.9440958385644;3755.6432138946757;3883.8834834954996;3901.882943511699;3908.1402091539817;105096.94903051025;106024.46987765089;106848;107089.46455267751;107316;10776.946115269373;11083.833742493869;11254.774904502028;11353.829692554618;11469.713257168638;22767;22954.540909181724;23153.421164470685;23153.88423057874;23185.420364491023;7482.625868706543;7735.226477352332;7741.729039483591;7750.10873749526;7820.921790782133;4229.492460904711;4422.159789639958;4488.281874900065;4555.726656400627;4616.815327386934;3546.6098729139776;3654.2691461707764;3668.724845636588;3675.228202077594;3679.5216621839527;635.745701719316;655.1810237203496;658.0162656828072;658.1444122640644;658.4798009572385;550.9256250406224;602.16299343912;625.8404106952698;626.5050610018035;638.8409315932761;8021.639026243833;8789.560521973875;8828.42615230008;9535.368747492077;9547.809043819085;554.1671194175802;558.9383139848338;639.7600899662626;661.1273119482333;667.8263651450658;4031.858884939013;4169.812358443816;4242.5757424257945;4519.706219095814;4545.136424079415;130388;132038.33980830034;134052.32973835067;138394.61605384017;139599.30200349135;108066.45966770273;117581.41209293899;117673.4116329413;119407;121534;53323.93352133013;64083;96514.03485965331;96604.51697741466;103381.48309258405;9327.906720932975;9383.624655013722;9436.490161574115;9450.621975120917;9554.570044347882;56221.8755624877;57046.7147664259;61973;63642.68178659077;64082;84169.57915210385;87895;91066;92288.53855730679;93966.53016734873;12285.360416150606;12658.683532911751;12834.55079072228;13051.869481305066;13082.953363731069;14363.640908977359;14388.28058597087;14610.634734131732;14740.926295368454;15211.239438027833;11054.613088541862;13197.20816750998;13210.933945330213;13357.033525897476;13606.591802245948;13144.0799144059;13301.534446294334;13443.395047223094;13612.93193534026;14005.929970350084;11832.881671183179;12837.743245134858;12896.567197666816;13286.933565332112;13425.79167874896;10064.949675251577;10961.287516311415;11430.371329576621;11500.309981401142;11632.825507617223;10889.945550272198;11020.559177632806;11024.338539687644;11186.23600972018;11334.60328888485 19:40:55 INFO - PROCESS | 1658 | __end_tp_report 19:40:55 INFO - PROCESS | 1658 | __start_cc_report 19:40:55 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1327 19:40:55 INFO - PROCESS | 1658 | __end_cc_report 19:40:55 INFO - PROCESS | 1658 | __startTimestamp1475635255290__endTimestamp 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | ------- Summary: start ------- 19:40:55 INFO - PROCESS | 1658 | Number of tests: 6 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5703.51 Median:5879.27 stddev:648.88 (11.0%) stddev-sans-first:612.37 19:40:55 INFO - PROCESS | 1658 | Values: 2896.8 3283.8 3322.3 3345.4 3368.7 5580.9 5623.3 5654.0 5655.9 5702.7 5652.1 5663.6 5716.0 5717.1 5833.2 5136.8 5187.7 5227.1 5227.6 5234.5 5150.5 5250.1 5256.1 5261.7 5274.4 5257.0 5261.3 5271.4 5276.8 5304.0 5103.2 5180.5 5183.9 5246.2 5262.4 5124.0 5165.3 5166.3 5172.1 5177.8 5502.3 5560.6 5591.8 5631.7 5643.6 6109.2 6189.4 6192.4 6206.0 6208.2 6275.4 6286.0 6287.8 6296.2 6297.3 5617.2 5692.5 5725.2 5743.0 5746.3 6251.1 6289.0 6290.6 6307.9 6321.3 6079.8 6096.1 6099.0 6099.4 6101.8 6126.3 6240.1 6245.6 6245.8 6253.3 6057.4 6090.3 6104.4 6105.3 6123.3 6092.1 6125.3 6129.2 6142.6 6156.3 4902.9 4903.1 4912.8 4931.6 4959.7 4345.1 4373.8 4390.1 4395.4 4438.4 5603.3 5657.1 5713.2 5716.1 5726.0 6170.5 6204.8 6247.5 6247.6 6284.2 5699.7 5720.6 5731.6 5746.1 5761.1 5366.3 5377.2 5394.5 5401.2 5402.7 4903.0 6216.8 6224.1 6274.7 6280.9 6217.0 6217.9 6223.1 6230.6 6238.4 6277.9 6286.6 6301.7 6306.9 6307.4 4872.2 6054.6 6071.5 6107.8 6143.2 6200.6 6281.4 6291.8 6299.3 6303.6 5844.7 6130.2 6130.4 6132.4 6148.3 5839.9 5969.6 6008.2 6110.6 6112.4 5862.6 5938.0 5942.6 5952.7 5972.4 6041.6 6117.6 6126.7 6129.1 6140.4 5706.3 5827.4 5879.3 5896.9 5911.8 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:65658.30 Median:38144.81 stddev:136903.97 (358.9%) stddev-sans-first:137200.66 19:40:55 INFO - PROCESS | 1658 | Values: 518.5 529.2 535.8 536.0 556.2 27810.4 40507.0 41920.8 41941.8 44142.8 43135.8 43435.6 43436.3 43470.7 43542.8 8635.6 9490.9 9679.3 9684.9 9702.0 8737.5 11837.2 12196.8 12205.7 12413.8 12105.5 12179.1 12189.3 12207.3 12207.9 12108.8 12232.5 12238.7 12248.8 12256.7 11807.7 11896.7 11898.8 11908.0 11927.8 40049.8 40322.0 40420.8 40472.0 40509.8 2726.5 3340.4 3439.1 3495.6 3691.9 23740.5 30156.2 30768.0 31052.9 31940.7 3128.8 3148.9 3173.5 3192.9 3281.9 468985.0 469608.0 469745.0 470600.0 482792.0 60873.0 61222.1 61226.0 61256.1 61276.0 63549.0 63967.7 64215.0 64431.0 64484.7 15865.4 16075.4 16081.6 16120.2 16145.1 11240.7 11468.7 11483.0 12482.6 12556.9 15438.2 15563.8 16907.1 17249.3 17344.7 5561.0 6670.5 6727.7 6758.2 6818.9 828.6 945.1 967.1 984.2 1084.2 28815.3 29315.4 29646.7 30271.4 30756.8 1018.7 1031.2 1039.4 1067.2 1081.7 14775.6 15420.8 15487.2 15589.9 15801.1 620023.0 626740.0 627861.0 628102.0 630286.0 57052.4 57175.1 57262.0 57367.0 57378.7 1095.6 1208.8 1253.7 1354.5 1373.3 26042.0 30706.9 31618.4 32127.5 32258.4 54824.5 55577.0 55593.7 55653.0 55758.7 415555.0 420650.0 420948.0 421337.0 424279.0 31550.7 34683.7 35558.6 39431.2 40030.6 39800.6 47526.8 47980.5 47985.0 49074.5 38862.0 39244.0 39346.8 40557.8 40914.6 38135.0 38555.0 38942.2 39471.8 39694.8 38023.0 38154.6 38359.0 38983.0 39283.8 30317.0 34203.2 34918.7 34952.8 34992.5 25198.0 25372.5 25441.2 25547.0 26152.5 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:33843.25 Median:3160.72 stddev:93560.99 (2960.1%) stddev-sans-first:93789.62 19:40:55 INFO - PROCESS | 1658 | Values: 332.4 351.6 356.7 377.4 384.4 14981.6 15179.2 16259.5 20788.4 20896.8 19461.0 19988.3 20611.6 20846.7 21099.8 5954.9 6158.2 6197.4 6211.1 6212.9 5108.6 6801.8 6874.5 6955.2 6968.5 6597.5 6737.3 6821.4 6881.3 6909.5 6713.0 6813.1 6854.1 6859.5 6868.3 5703.9 5894.4 5896.1 5900.8 5901.8 26847.3 26867.5 26885.1 26931.0 26989.0 2425.7 2505.1 2620.7 3159.9 3161.5 9638.5 10287.0 10325.7 10370.1 10415.5 1754.9 1791.5 1816.8 1840.8 1841.3 96953.5 116418.4 120302.0 121854.0 122306.0 10148.7 10598.5 11477.4 11497.8 11637.2 14341.8 14365.6 14472.3 14491.6 14506.9 8362.9 8372.0 8389.8 8466.9 8568.5 8348.6 8382.3 8383.1 8531.3 8534.0 4272.0 4406.3 4496.0 4505.7 4558.3 1670.3 1918.8 1919.4 1921.6 1924.5 810.7 811.5 899.3 1005.2 1042.6 19758.8 21500.0 21586.2 21639.9 21652.1 695.5 882.2 927.0 939.9 957.1 10652.5 11148.4 11210.7 11247.6 11254.5 503492.0 525984.0 576566.0 578473.0 599672.0 92479.5 132011.0 136671.0 142264.0 143132.0 871.8 946.6 971.8 990.8 997.1 18374.4 19236.7 19254.7 19264.0 19277.9 36600.8 52214.7 52967.8 53885.7 55597.7 77284.0 78147.6 78842.0 78941.8 78987.0 7751.0 7949.0 8063.1 8161.2 8207.5 12120.6 12741.6 12829.6 13138.8 13305.3 9069.6 10763.5 11101.7 11195.5 11214.8 10236.4 10741.4 10770.4 11300.8 11407.5 10989.8 11341.9 11616.1 11716.8 11806.4 7977.2 7985.9 8071.3 8115.6 8190.2 9608.8 10895.5 11235.9 11318.4 11334.3 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5767.93 Median:4776.79 stddev:3266.73 (68.4%) stddev-sans-first:3259.70 19:40:55 INFO - PROCESS | 1658 | Values: 1439.4 1488.0 1667.6 1837.6 1893.9 8541.3 9043.4 9049.4 9065.3 9066.1 7975.4 8292.3 8296.4 8669.8 8909.2 2360.0 2387.8 2396.2 2396.5 2397.1 2354.7 2358.1 2400.4 2400.6 2408.6 2377.3 2393.5 2398.9 2399.1 2409.2 2357.2 2363.0 2365.6 2373.1 2389.3 2355.2 2363.6 2371.1 2375.8 2380.4 8951.9 8974.6 9077.4 9093.5 9102.1 5265.3 5307.2 5431.6 5431.8 5444.7 3628.5 3648.6 3714.5 3723.0 3726.7 1068.7 1068.9 1078.4 1096.6 1100.9 12006.5 12099.8 12120.0 12123.4 12195.9 4485.5 4604.3 4759.9 4793.7 4823.8 10057.6 10086.3 10105.3 10113.4 10147.4 5324.9 5327.3 5327.4 5328.4 5334.0 4366.8 4398.5 4516.5 4735.0 4861.1 3318.8 3832.7 3872.8 3992.5 3992.7 1255.9 1281.5 1282.3 1288.8 1289.4 809.4 850.4 878.7 927.7 944.3 5809.9 5840.6 5974.7 6067.8 6288.2 738.2 880.1 908.8 950.2 1006.9 3651.0 3916.2 4034.3 4034.5 4080.3 10384.9 10661.5 10887.5 10958.5 11049.3 11002.2 11015.6 11018.7 11020.5 11041.0 8002.3 8351.7 8392.5 8409.4 8420.3 5694.4 5733.9 5803.8 5806.3 5891.3 9975.2 10235.7 10306.2 10318.3 10321.9 9828.8 10207.4 10291.7 10376.2 10451.3 6568.0 6792.0 6961.4 7106.8 7179.0 7975.1 8243.0 8551.4 8555.7 8609.9 7387.3 7530.1 7569.5 7627.7 7652.7 7399.7 7428.6 7485.3 7490.0 7604.8 6914.4 7078.6 7080.8 7085.6 7099.9 4914.7 4985.9 5042.1 5069.7 5146.6 4952.8 5010.9 5130.9 5159.2 5235.4 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4488.50 Median:4863.78 stddev:1404.56 (28.9%) stddev-sans-first:1372.92 19:40:55 INFO - PROCESS | 1658 | Values: 302.8 304.3 311.5 317.4 318.3 4588.5 5205.7 5423.8 5439.1 5452.1 4795.5 5166.3 5254.4 5290.8 5364.7 3432.7 3986.9 4134.7 4135.5 4172.6 3618.9 3937.0 4112.9 4170.2 4181.2 3481.3 3527.6 3925.9 4000.2 4017.6 3346.5 4012.9 4035.9 4056.5 4059.3 3042.0 3562.1 3576.1 3582.8 3595.4 4556.1 4619.2 5196.6 5256.2 5334.6 4210.8 4684.4 4685.7 4700.4 4704.0 5004.7 5619.7 5650.0 5667.9 5717.7 3307.9 3368.3 3588.0 3609.1 3823.0 5872.1 5915.7 5921.4 5922.8 5935.3 3904.6 3907.6 3931.1 3932.8 3971.8 3303.4 3733.1 3887.0 3948.6 3953.7 4345.9 4548.2 4596.6 4620.9 4665.6 5110.9 5440.9 5675.7 5725.4 5754.7 1181.6 1261.3 1334.2 1353.0 1361.3 809.4 811.6 816.1 824.9 827.4 3801.3 3926.7 4443.5 4450.4 4471.4 4300.9 4647.8 4820.8 4825.6 4884.9 4874.7 5095.3 5203.8 5207.1 5229.8 2571.9 2915.8 3011.8 3051.8 3066.8 5025.8 5478.3 5542.4 6027.4 6050.8 4774.7 5925.4 5957.4 6024.0 6055.5 5060.8 5828.8 6025.0 6026.7 6052.0 4949.9 5441.0 5737.7 5833.4 5866.7 5269.7 5663.2 5702.3 5731.7 5757.8 5150.4 5906.4 6034.8 6042.2 6044.3 3879.5 3909.6 3918.8 3921.4 3927.4 4834.4 5848.7 5873.9 5883.2 5934.4 4689.8 5427.4 5714.8 5731.5 5773.6 4412.0 4583.1 4731.8 4953.7 5602.8 4852.9 5292.6 5571.8 5608.7 5610.5 5365.5 5655.6 5675.4 5707.2 5757.6 4239.6 5193.7 5287.5 5559.8 5560.4 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:23080.79 Median:3703.23 stddev:35878.40 (968.8%) stddev-sans-first:35939.60 19:40:55 INFO - PROCESS | 1658 | Values: 674.0 707.0 714.2 747.7 852.0 11523.2 13437.6 13459.7 13603.0 13655.6 13359.5 13932.3 13995.2 14163.8 14211.6 722.9 874.9 877.1 879.3 879.5 691.8 714.5 719.9 850.1 870.7 911.1 912.8 913.9 914.7 916.5 906.9 908.5 908.8 909.0 909.6 532.9 550.6 551.1 552.5 680.0 12459.4 13893.5 14354.7 14424.7 14733.9 14151.5 14384.9 14530.8 14533.9 14592.5 11579.6 11676.6 11681.4 12148.9 12268.9 3726.9 3755.6 3883.9 3901.9 3908.1 105096.9 106024.5 106848.0 107089.5 107316.0 10776.9 11083.8 11254.8 11353.8 11469.7 22767.0 22954.5 23153.4 23153.9 23185.4 7482.6 7735.2 7741.7 7750.1 7820.9 4229.5 4422.2 4488.3 4555.7 4616.8 3546.6 3654.3 3668.7 3675.2 3679.5 635.7 655.2 658.0 658.1 658.5 550.9 602.2 625.8 626.5 638.8 8021.6 8789.6 8828.4 9535.4 9547.8 554.2 558.9 639.8 661.1 667.8 4031.9 4169.8 4242.6 4519.7 4545.1 130388.0 132038.3 134052.3 138394.6 139599.3 108066.5 117581.4 117673.4 119407.0 121534.0 53323.9 64083.0 96514.0 96604.5 103381.5 9327.9 9383.6 9436.5 9450.6 9554.6 56221.9 57046.7 61973.0 63642.7 64082.0 84169.6 87895.0 91066.0 92288.5 93966.5 12285.4 12658.7 12834.6 13051.9 13083.0 14363.6 14388.3 14610.6 14740.9 15211.2 11054.6 13197.2 13210.9 13357.0 13606.6 13144.1 13301.5 13443.4 13612.9 14005.9 11832.9 12837.7 12896.6 13286.9 13425.8 10064.9 10961.3 11430.4 11500.3 11632.8 10889.9 11020.6 11024.3 11186.2 11334.6 19:40:55 INFO - PROCESS | 1658 | -------- Summary: end -------- 19:40:55 INFO - PROCESS | 1658 | 19:40:55 INFO - TEST-INFO | 1658: exit 0 19:40:55 INFO - TEST-OK | dromaeo_css | took 1119261ms 19:40:55 INFO - TEST-START | kraken 19:40:55 INFO - operating with platform_type : mac_ 19:40:55 INFO - Initialising browser for kraken test... 19:40:55 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpsMMOQP/profile http://localhost:49274/getInfo.html) 19:40:58 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 19:40:58 INFO - PROCESS | 1665 | colorDepth:24 19:40:58 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 19:40:58 INFO - PROCESS | 1665 | __metrics 19:40:58 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:40:58 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:40:59 INFO - TEST-INFO | 1665: exit 0 19:40:59 INFO - Browser initialized. 19:40:59 INFO - Running cycle 1/1 for kraken test... 19:40:59 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpsMMOQP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:41:11 INFO - PROCESS | 1666 | RSS: Main: 235814912 19:41:11 INFO - PROCESS | 1666 | 19:41:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 19:41:14 INFO - PROCESS | 1666 | RSS: Main: 294723584 19:41:14 INFO - PROCESS | 1666 | 19:41:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 19:41:18 INFO - PROCESS | 1666 | RSS: Main: 293597184 19:41:18 INFO - PROCESS | 1666 | 19:41:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 19:41:22 INFO - PROCESS | 1666 | RSS: Main: 384503808 19:41:22 INFO - PROCESS | 1666 | 19:41:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 19:41:24 INFO - PROCESS | 1666 | RSS: Main: 285736960 19:41:24 INFO - PROCESS | 1666 | 19:41:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:41:27 INFO - PROCESS | 1666 | RSS: Main: 290623488 19:41:27 INFO - PROCESS | 1666 | 19:41:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 19:41:30 INFO - PROCESS | 1666 | RSS: Main: 327950336 19:41:30 INFO - PROCESS | 1666 | 19:41:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 19:41:34 INFO - PROCESS | 1666 | RSS: Main: 330674176 19:41:34 INFO - PROCESS | 1666 | 19:41:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 19:41:37 INFO - PROCESS | 1666 | RSS: Main: 328290304 19:41:37 INFO - PROCESS | 1666 | 19:41:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:41:39 INFO - PROCESS | 1666 | RSS: Main: 301891584 19:41:39 INFO - PROCESS | 1666 | 19:41:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:41:42 INFO - PROCESS | 1666 | RSS: Main: 305446912 19:41:42 INFO - PROCESS | 1666 | 19:41:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:41:45 INFO - PROCESS | 1666 | RSS: Main: 309678080 19:41:45 INFO - PROCESS | 1666 | 19:41:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:41:49 INFO - PROCESS | 1666 | RSS: Main: 325332992 19:41:49 INFO - PROCESS | 1666 | 19:41:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:41:52 INFO - PROCESS | 1666 | RSS: Main: 319500288 19:41:52 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 19:41:54 INFO - PROCESS | 1666 | RSS: Main: 363118592 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | __start_tp_report 19:41:54 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 19:41:54 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 19:41:54 INFO - PROCESS | 1666 | |i|pagename|runs| 19:41:54 INFO - PROCESS | 1666 | |0;ai-astar;113;115;114;113;113;114;113;113;112;113 19:41:54 INFO - PROCESS | 1666 | |1;audio-beat-detection;153;125;146;146;143;142;143;114;145;122 19:41:54 INFO - PROCESS | 1666 | |2;audio-dft;182;184;184;183;182;180;182;182;179;180 19:41:54 INFO - PROCESS | 1666 | |3;audio-fft;80;62;59;87;63;59;58;60;82;58 19:41:54 INFO - PROCESS | 1666 | |4;audio-oscillator;85;71;92;93;54;71;120;95;68;90 19:41:54 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;84;87;84;86;84;87;85;87;83 19:41:54 INFO - PROCESS | 1666 | |6;imaging-darkroom;93;94;94;93;93;94;93;93;94;93 19:41:54 INFO - PROCESS | 1666 | |7;imaging-desaturate;95;98;143;92;88;92;88;89;92;84 19:41:54 INFO - PROCESS | 1666 | |8;json-parse-financial;58;55;55;55;57;56;55;56;56;54 19:41:54 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;65;72;71;72;71;69;67;71;69;68 19:41:54 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;85;89;86;76;95;95;107;108;107;91 19:41:54 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;146;178;142;157;156;156;151;145;183;147 19:41:54 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;173;172;172;170;171;170;225;238;172;172 19:41:54 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;68;71;81;85;88;75;71;68;67 19:41:54 INFO - PROCESS | 1666 | __end_tp_report 19:41:54 INFO - PROCESS | 1666 | __start_cc_report 19:41:54 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1960 19:41:54 INFO - PROCESS | 1666 | __end_cc_report 19:41:54 INFO - PROCESS | 1666 | __startTimestamp1475635314795__endTimestamp 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | ------- Summary: start ------- 19:41:54 INFO - PROCESS | 1666 | Number of tests: 14 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 19:41:54 INFO - PROCESS | 1666 | Values: 113.0 115.0 114.0 113.0 113.0 114.0 113.0 113.0 112.0 113.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:144.00 stddev:12.78 (8.9%) stddev-sans-first:12.33 19:41:54 INFO - PROCESS | 1666 | Values: 153.0 125.0 146.0 146.0 143.0 142.0 143.0 114.0 145.0 122.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:181.80 Median:182.00 stddev:1.69 (0.9%) stddev-sans-first:1.79 19:41:54 INFO - PROCESS | 1666 | Values: 182.0 184.0 184.0 183.0 182.0 180.0 182.0 182.0 179.0 180.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.50 stddev:11.42 (18.3%) stddev-sans-first:11.07 19:41:54 INFO - PROCESS | 1666 | Values: 80.0 62.0 59.0 87.0 63.0 59.0 58.0 60.0 82.0 58.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:87.50 stddev:18.54 (21.2%) stddev-sans-first:19.66 19:41:54 INFO - PROCESS | 1666 | Values: 85.0 71.0 92.0 93.0 54.0 71.0 120.0 95.0 68.0 90.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.72 (2.0%) stddev-sans-first:1.56 19:41:54 INFO - PROCESS | 1666 | Values: 88.0 84.0 87.0 84.0 86.0 84.0 87.0 85.0 87.0 83.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 19:41:54 INFO - PROCESS | 1666 | Values: 93.0 94.0 94.0 93.0 93.0 94.0 93.0 93.0 94.0 93.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:92.00 stddev:16.94 (18.4%) stddev-sans-first:17.96 19:41:54 INFO - PROCESS | 1666 | Values: 95.0 98.0 143.0 92.0 88.0 92.0 88.0 89.0 92.0 84.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.16 (2.1%) stddev-sans-first:0.88 19:41:54 INFO - PROCESS | 1666 | Values: 58.0 55.0 55.0 55.0 57.0 56.0 55.0 56.0 56.0 54.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:71.00 stddev:2.32 (3.3%) stddev-sans-first:1.80 19:41:54 INFO - PROCESS | 1666 | Values: 65.0 72.0 71.0 72.0 71.0 69.0 67.0 71.0 69.0 68.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:87.50 stddev:10.74 (12.3%) stddev-sans-first:10.90 19:41:54 INFO - PROCESS | 1666 | Values: 85.0 89.0 86.0 76.0 95.0 95.0 107.0 108.0 107.0 91.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:156.00 stddev:13.89 (8.9%) stddev-sans-first:14.25 19:41:54 INFO - PROCESS | 1666 | Values: 146.0 178.0 142.0 157.0 156.0 156.0 151.0 145.0 183.0 147.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.50 Median:172.00 stddev:25.50 (14.8%) stddev-sans-first:26.76 19:41:54 INFO - PROCESS | 1666 | Values: 173.0 172.0 172.0 170.0 171.0 170.0 225.0 238.0 172.0 172.0 19:41:54 INFO - PROCESS | 1666 | 19:41:54 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:73.00 stddev:7.67 (10.5%) stddev-sans-first:7.90 19:41:54 INFO - PROCESS | 1666 | Values: 69.0 68.0 71.0 81.0 85.0 88.0 75.0 71.0 68.0 67.0 19:41:54 INFO - PROCESS | 1666 | -------- Summary: end -------- 19:41:54 INFO - PROCESS | 1666 | 19:41:55 INFO - TEST-INFO | 1666: exit 0 19:41:55 INFO - TEST-OK | kraken | took 59838ms 19:41:55 INFO - SUITE-END | took 1179s 19:41:55 INFO - Completed test suite (00:19:39) 19:41:55 INFO - javascript benchmark 19:41:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2896.768258539317, 3283.8358082095906, 3322.3355328934203, 3345.3978283908887, 3368.730501559874, 5580.888382232353, 5623.268975033245, 5653.98228318903, 5655.943440565593, 5702.714864256791, 5652.09566469365, 5663.575231857607, 5716.028275193216, 5717.085266357383, 5833.154192642064, 5136.768845401947, 5187.6887386756725, 5227.059129356724, 5227.581793456533, 5234.528892399683, 5150.484951504857, 5250.081235783741, 5256.080185967459, 5261.710605916673, 5274.4198138204765, 5257.027449921758, 5261.289725886994, 5271.393789714174, 5276.762545685434, 5303.973480132594, 5103.157978933508, 5180.507851754077, 5183.896322073575, 5246.186841039606, 5262.447443018466, 5123.974380128076, 5165.302684137669, 5166.328377310963, 5172.094883395391, 5177.767000484987, 5502.339719233678, 5560.638558493685, 5591.832245032656, 5631.662100273997, 5643.6049476536655, 6109.2224806663635, 6189.442950134465, 6192.411720886508, 6206.007038873759, 6208.192934918432, 6275.372462753734, 6286.0, 6287.811365658992, 6296.150019747367, 6297.307296197415, 5617.157426386075, 5692.544596432275, 5725.198472213893, 5742.971285143548, 5746.281714785652, 6251.062340648939, 6289.0252010938375, 6290.622562646256, 6307.905381419282, 6321.304656487782, 6079.75680972765, 6096.116063170815, 6098.969505152446, 6099.390060993954, 6101.816945491642, 6126.295476020226, 6240.095186197976, 6245.594036387621, 6245.843853903598, 6253.343398943137, 6057.394260573996, 6090.299615544269, 6104.420080092473, 6105.32841387447, 6123.295820980555, 6092.130708583294, 6125.265974675666, 6129.172561704113, 6142.600730952474, 6156.29202641693, 4902.926456103161, 4903.068417000758, 4912.754362281871, 4931.580815630711, 4959.70241785494, 4345.109252603209, 4373.781310934504, 4390.100029493946, 4395.362672412546, 4438.356438316426, 5603.271574695264, 5657.066584013592, 5713.22871412354, 5716.085426331767, 5725.971370143123, 6170.50635949123, 6204.751809927552, 6247.468965137923, 6247.625142491374, 6284.214473190851, 5699.686517241508, 5720.599558030898, 5731.570132240098, 5746.109353050284, 5761.078227483583, 5366.302380690485, 5377.166539186433, 5394.541463975527, 5401.162819762943, 5402.6758394496455, 4902.975485122552, 6216.844578885564, 6224.097505861715, 6274.686265686789, 6280.905786413299, 6217.036359364306, 6217.875642487215, 6223.066540019034, 6230.59501132425, 6238.438540531374, 6277.874442511215, 6286.591371560834, 6301.6534090625455, 6306.873862522815, 6307.400796924285, 4872.244802055687, 6054.606450580698, 6071.483923866521, 6107.765388666889, 6143.201383820075, 6200.627962322275, 6281.371862813682, 6291.842703932438, 6299.307076221704, 6303.558750887394, 5844.707764611838, 6130.172426722335, 6130.386961303834, 6132.38676132401, 6148.292946311231, 5839.854003649943, 5969.582129250911, 6008.1588577598295, 6110.57225994194, 6112.388761123852, 5862.589618726648, 5938.0, 5942.61373010753, 5952.702364881825, 5972.432618901197, 6041.577089603686, 6117.632942023315, 6126.724297406538, 6129.141920131275, 6140.416660417253, 5706.343770466284, 5827.388124246893, 5879.265091863517, 5896.941030589639, 5911.822645320647], "value": 5660.985794586439, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.5359103602276, 529.2141170362012, 535.8097875254284, 536.0458384076343, 556.1518684006886, 27810.443791124166, 40506.987325316884, 41920.790396047974, 41941.7902910485, 44142.77928610353, 43135.784321078354, 43435.565644343544, 43436.34845477313, 43470.695879123676, 43542.78228608853, 8635.568221588926, 9490.857637135432, 9679.322447428684, 9684.934657187703, 9701.951490242538, 8737.475751454933, 11837.171398002143, 12196.817047744247, 12205.694857628541, 12413.813792793115, 12105.455254513568, 12179.086568507353, 12189.26864388135, 12207.267563946147, 12207.901288883999, 12108.81836772449, 12232.510699572007, 12238.694032649166, 12248.755024899541, 12256.693582660415, 11807.704807379885, 11896.702582435422, 11898.821517677155, 11908.0, 11927.821082683766, 40049.79975100135, 40322.0, 40420.79789601063, 40472.0, 40509.79745101256, 2726.4819684260124, 3340.415427300237, 3439.1402149462633, 3495.615482296945, 3691.8523259069875, 23740.525189496115, 30156.24609384783, 30768.0, 31052.913148039937, 31940.680593194236, 3128.7809853310278, 3148.889788857402, 3173.4763763978917, 3192.944959900936, 3281.9343613127844, 468985.0, 469608.0, 469745.0, 470600.0, 482792.0, 60873.0, 61222.081668775005, 61226.0, 61256.081158782654, 61276.0, 63549.0, 63967.680161598895, 64215.0, 64431.0, 64484.71030579362, 15865.444709435114, 16075.437359692354, 16081.597960050858, 16120.193990300439, 16145.112018839076, 11240.718982025515, 11468.713282167846, 11483.0, 12482.625521234377, 12556.937215313865, 15438.228088595526, 15563.832712546604, 16907.07011114376, 17249.310027599007, 17344.739828902577, 5561.0, 6670.533062685566, 6727.730890764315, 6758.222804377559, 6818.897716534256, 828.5939889454124, 945.0785484152896, 967.1199208720171, 984.1978787288338, 1084.18940369583, 28815.279618009718, 29315.413691726048, 29646.703532964395, 30271.394572108875, 30756.846215769227, 1018.6587493189717, 1031.2368847053276, 1039.3867618105355, 1067.199600299775, 1081.7457897394106, 14775.556733298019, 15420.768688469681, 15487.225638718019, 15589.922050389676, 15801.070503710995, 620023.0, 626740.0, 627861.0, 628102.0, 630286.0, 57052.42947570554, 57175.14237286444, 57262.0, 57367.0, 57378.7131064342, 1095.5508241621058, 1208.7522057978094, 1253.7429826885284, 1354.4514471639002, 1373.2996171952177, 26042.0, 30706.925257615876, 31618.367632647678, 32127.51808722918, 32258.35483290368, 54824.45175548353, 55577.0, 55593.72203138958, 55653.0, 55758.721206395334, 415555.0, 420650.0, 420948.0, 421337.0, 424279.0, 31550.684493154775, 34683.65316346804, 35558.644413555536, 39431.21137577175, 40030.59969400269, 39800.60199397969, 47526.76236618933, 47980.520194799, 47985.0, 49074.509254906996, 38862.0, 39244.0, 39346.803265983486, 40557.797211014935, 40914.590854091075, 38135.0, 38555.036124097125, 38942.221155577296, 39471.80264098776, 39694.80152599219, 38023.0, 38154.61845381511, 38359.0, 38983.0, 39283.80358098191, 30317.0, 34203.199896035825, 34918.65081349154, 34952.82523587366, 34992.47511287282, 25198.0, 25372.492550148523, 25441.236762897144, 25547.0, 26152.476950460503], "value": 20258.204151708218, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.38176990797115, 351.6044449993757, 356.7342329964177, 377.4319648928362, 384.3830651803855, 14981.5505534834, 15179.2410379481, 16259.51221463355, 20788.376348709524, 20896.791032089674, 19461.0, 19988.30040948567, 20611.587768244626, 20846.68729969052, 21099.789002109974, 5954.910676339848, 6158.199434073565, 6197.442230199281, 6211.130441738161, 6212.9068063979075, 5108.565771909392, 6801.795946121624, 6874.450043996493, 6955.165380154389, 6968.547044442121, 6597.538172327915, 6737.3262673732725, 6821.420179284767, 6881.277465866087, 6909.516333856632, 6713.0, 6813.114295141625, 6854.143232095988, 6859.485538584576, 6868.278830722802, 5703.885922281531, 5894.410558944114, 5896.145058966469, 5900.822975310747, 5901.793437229677, 26847.32881677954, 26867.462650746875, 26885.0590229343, 26931.017761714145, 26989.0, 2425.6967879015124, 2505.148249595128, 2620.737926207383, 3159.921001974969, 3161.525771134325, 9638.469884156368, 10286.971302869653, 10325.741856453573, 10370.066693997502, 10415.479226038668, 1754.9385771498064, 1791.4983804534752, 1816.8455681267108, 1840.8067152949016, 1841.2542920117373, 96953.51523242409, 116418.41790790991, 120302.0, 121854.0, 122306.0, 10148.680671512657, 10598.523066462029, 11477.36874471912, 11497.827532587018, 11637.185397022296, 14341.78487322691, 14365.64085897861, 14472.348744306531, 14491.637709057148, 14506.85493145055, 8362.916370836336, 8372.03721572015, 8389.790255243546, 8466.872996905051, 8568.52873091986, 8348.582570871553, 8382.329413646894, 8383.11977242393, 8531.274841638531, 8533.975922889293, 4271.974726065722, 4406.25093734066, 4495.965927836616, 4505.7071290366675, 4558.316252562142, 1670.3151707799743, 1918.827305542508, 1919.433767038727, 1921.5868588253313, 1924.5284905198316, 810.7284059839902, 811.5009269299456, 899.2581120575551, 1005.1605561821848, 1042.5829668132808, 19758.814471131776, 21500.032498537603, 21586.19182870871, 21639.891800541212, 21652.102621528276, 695.5027155583681, 882.2104216725994, 926.9721908342625, 939.9295052871062, 957.1051067252002, 10652.520636571373, 11148.386838723785, 11210.719732006764, 11247.550097996153, 11254.49354779037, 503492.0, 525984.0, 576566.0, 578473.0, 599672.0, 92479.53760231155, 132011.01983470254, 136671.0, 142264.0, 143132.0, 871.808202195516, 946.6071580294101, 971.7813491964251, 990.8315586350337, 997.1175509673845, 18374.448766537025, 19236.71144932855, 19254.7111793326, 19264.0, 19277.939713315904, 36600.81699591485, 52214.69463263449, 52967.82193068428, 53885.730571346896, 55597.722011389684, 77284.0, 78147.6092619556, 78842.0, 78941.81587276081, 78987.0, 7750.961245193738, 7949.0, 8063.113057563781, 8161.224683655043, 8207.522645923791, 12120.575779847662, 12741.617751467471, 12829.55096571653, 13138.80291795643, 13305.268210248529, 9069.591868366071, 10763.515641796295, 11101.722456938642, 11195.496202670736, 11214.831777523506, 10236.436995965294, 10741.409222492837, 10770.407627580558, 11300.773984520429, 11407.543698251977, 10989.835152472559, 11341.943290283496, 11616.070714343, 11716.765664686829, 11806.409679516162, 7977.162397948132, 7985.8802117969435, 8071.273585377109, 8115.553644549606, 8190.221928916743, 9608.75978100553, 10895.455227238448, 11235.887641123483, 11318.434078295888, 11334.263272887236], "value": 9626.525481639761, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.4098419647946, 1487.9584290996302, 1667.5664327274912, 1837.6232872261187, 1893.9337123200692, 8541.316694664427, 9043.412178208411, 9049.41178823376, 9065.32010099242, 9066.138716821899, 7975.40184486163, 8292.336613070955, 8296.37777166712, 8669.783255418617, 8909.198172164504, 2360.0441821062454, 2387.8209134314907, 2396.1613435297595, 2396.5087157132834, 2397.0531640002187, 2354.740978492373, 2358.1157066100213, 2400.411899084727, 2400.5558971590226, 2408.590539608262, 2377.3105799318178, 2393.509330587234, 2398.8920498577518, 2399.1363109280646, 2409.168836751318, 2357.1749887539395, 2362.9763702363102, 2365.5860224460653, 2373.062640257106, 2389.307100940725, 2355.234548771656, 2363.5745565798147, 2371.1226846066893, 2375.7743014413604, 2380.357303528038, 8951.865722014176, 8974.641014359418, 9077.36458447909, 9093.545322733902, 9102.089791020911, 5265.2628631991165, 5307.203919412081, 5431.646942948696, 5431.809886653988, 5444.700541470217, 3628.546431696038, 3648.6168952259895, 3714.4799728038097, 3723.0134014486184, 3726.6832319252894, 1068.6633710381284, 1068.9144868410508, 1078.3691540448847, 1096.627146770102, 1100.862392200975, 12006.459709313101, 12099.818502722466, 12120.030397568173, 12123.393830308538, 12195.93902030493, 4485.529019452975, 4604.263317869125, 4759.881002974884, 4793.699024468275, 4823.807047718122, 10057.597696092074, 10086.344387614781, 10105.29262951601, 10113.393196408091, 10147.441890696082, 5324.893502129936, 5327.334083239595, 5327.413984461706, 5328.387235467971, 5333.973330133324, 4366.847160349373, 4398.516163222043, 4516.548345165523, 4734.95265047352, 4861.124997500484, 3318.7510936679846, 3832.693384529231, 3872.845086196577, 3992.4610177626228, 3992.7205095643026, 1255.918365306252, 1281.4810001949224, 1282.2883299768719, 1288.7873500872438, 1289.3875409180753, 809.4293523066342, 850.4429598612918, 878.6819770344483, 927.6845872403416, 944.2587568758491, 5809.912851307234, 5840.561957853093, 5974.671393073423, 6067.848303792352, 6288.151099601587, 738.1732459645232, 880.1418616848649, 908.7728067983004, 950.1780959470009, 1006.854006169116, 3650.9634903651154, 3916.1580260244186, 4034.3141665916864, 4034.495688038995, 4080.2859499587453, 10384.948075259575, 10661.520231589597, 10887.455627218607, 10958.452077396258, 11049.33703977764, 11002.174836887265, 11015.559377624806, 11018.669439916815, 11020.50407731654, 11041.0, 8002.279794818494, 8351.749447516584, 8392.538410387488, 8409.369297302605, 8420.326373890073, 5694.37710252576, 5733.885322293448, 5803.82588522345, 5806.332271788799, 5891.293044834647, 9975.20198384113, 10235.7441063974, 10306.227032972707, 10318.329308594917, 10321.90342897143, 9828.754281142743, 10207.387556746618, 10291.691249262534, 10376.221783366127, 10451.268411211142, 6567.9671601641685, 6791.966040169767, 6961.443084553122, 7106.822329441805, 7178.964105179441, 7975.082865470546, 8243.010838699394, 8551.444156130081, 8555.700550480704, 8609.870851937101, 7387.298206670358, 7530.134034586093, 7569.545827250383, 7627.694892204471, 7652.73215437457, 7399.667014984231, 7428.591427471325, 7485.251474852471, 7489.962550187214, 7604.8479030417975, 6914.412274956685, 7078.646067696697, 7080.787576372717, 7085.610291434021, 7099.893501597377, 4914.680545764514, 4985.875353116056, 5042.134410268224, 5069.746512674278, 5146.562542183956, 4952.752362381939, 5010.899782004267, 5130.8973820524125, 5159.174532075001, 5235.3717553893775], "value": 4620.303862875965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.750231059376, 304.2667172115204, 311.4860480207658, 317.393777884241, 318.33626887938647, 4588.54114588541, 5205.713685747282, 5423.810166644168, 5439.129739241722, 5452.127659574464, 4795.520447955203, 5166.276721259027, 5254.369475662917, 5290.841274761754, 5364.7317634118335, 3432.7425443091806, 3986.8803935881824, 4134.7312424692445, 4135.524414692319, 4172.603602657743, 3618.9095272618133, 3937.0, 4112.938305925413, 4170.18596910345, 4181.205570941526, 3481.346360478765, 3527.6472352764645, 3925.9018524536973, 4000.219957108365, 4017.5781542938007, 3346.548215990859, 4012.936571808473, 4035.8609936733283, 4056.492938382702, 4059.289624315748, 3041.9847900760574, 3562.109472631842, 3576.141725985771, 3582.7671201371827, 3595.442706380489, 4556.066006468666, 4619.221599684112, 5196.558292545138, 5256.1852912798195, 5334.59990500714, 4210.789460526992, 4684.391029166187, 4685.742284174369, 4700.365450664151, 4704.0, 5004.674696144739, 5619.662820230799, 5649.971750141264, 5667.8583035424035, 5717.656940583537, 3307.884224052171, 3368.309496553201, 3588.0312315674623, 3609.06164397256, 3822.9617703822883, 5872.089826076922, 5915.704214789243, 5921.378255283219, 5922.763089476416, 5935.287765468128, 3904.590018048064, 3907.5897030811925, 3931.1327119617954, 3932.803359831997, 3971.8411263549133, 3303.3888730584918, 3733.086320151014, 3886.9505233586774, 3948.6051394860283, 3953.723239373216, 4345.869623911288, 4548.1807277088765, 4596.6322694185055, 4620.861374158781, 4665.5567721066445, 5110.897782044338, 5440.911817636488, 5675.744591493392, 5725.427457254241, 5754.6547207167705, 1181.5864447443325, 1261.2810697902107, 1334.2128144394853, 1352.9729405411836, 1361.339750221142, 809.4091313341294, 811.5779794506941, 816.1145157504055, 824.9440715883678, 827.3546633625782, 3801.315763162602, 3926.6858651307825, 4443.466783985942, 4450.35469856869, 4471.3516540102155, 4300.935485967713, 4647.837325693585, 4820.831270905502, 4825.5898248648555, 4884.876478409986, 4874.707517548958, 5095.305903220135, 5203.791848326025, 5207.073382848371, 5229.764660590206, 2571.922842314696, 2915.8482399452523, 3011.7741169412384, 3051.794541156231, 3066.803946460882, 5025.84922452327, 5478.342598888079, 5542.418046105101, 6027.397260273938, 6050.848728781815, 4774.665773395898, 5925.377835327315, 5957.434043765836, 6023.975924092914, 6055.515558755289, 5060.797568097235, 5828.766849326064, 6025.0, 6026.728797204224, 6051.969740151271, 4949.9063085571515, 5440.972795135999, 5737.741801618854, 5833.416658334302, 5866.67733274674, 5269.683818970874, 5663.207150998781, 5702.287214098238, 5731.713414329351, 5757.769689212385, 5150.381954165524, 5906.379830117776, 6034.758609655741, 6042.214512113573, 6044.335123136363, 3879.4956655634587, 3909.6481316680497, 3918.843246270117, 3921.3922741377805, 3927.4108883666536, 4834.444038935427, 5848.736806843787, 5873.94411955812, 5883.235179426647, 5934.388868897671, 4689.835855745033, 5427.375851776938, 5714.79998200061, 5731.484166425042, 5773.566982476415, 4411.9631886506, 4583.106294272585, 4731.834385796481, 4953.653244272867, 5602.831915042555, 4852.8544143675745, 5292.576593872558, 5571.7771289149605, 5608.691521966331, 5610.49505544503, 5365.463453654604, 5655.575831812548, 5675.432456754456, 5707.230758464889, 5757.596968212185, 4239.554846741171, 5193.714345710871, 5287.471252874681, 5559.805406810742, 5560.4161563035295], "value": 4040.9250770857466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.9831504212398, 706.9893951590727, 714.2036629158488, 747.7233423633256, 851.9616617252223, 11523.19337646364, 13437.596872093829, 13459.663508412295, 13603.0, 13655.590332290023, 13359.465621375133, 13932.303384830744, 13995.160290382584, 14163.787543186834, 14211.573652790406, 722.9132504099503, 874.9300055995506, 877.0659247900996, 879.3141349747175, 879.5118709116438, 691.754427178352, 714.474860977184, 719.8776208044645, 850.0988951711175, 870.651739304277, 911.1389736698823, 912.8219997100568, 913.9443942246716, 914.7484441778531, 916.5188276155005, 906.863970404438, 908.4594666173682, 908.8046070094895, 909.0273407454027, 909.595230122599, 532.8641196494908, 550.6090675620275, 551.0796969061677, 552.5165480204821, 679.9694013769392, 12459.377031148406, 13893.513727019506, 14354.712905741826, 14424.71150576993, 14733.92633036828, 14151.504697335648, 14384.928075359661, 14530.782038269434, 14533.927330363385, 14592.489262875748, 11579.594714184963, 11676.613782003295, 11681.415929203591, 12148.87851121495, 12268.877311226863, 3726.9440958385644, 3755.6432138946757, 3883.8834834954996, 3901.882943511699, 3908.1402091539817, 105096.94903051025, 106024.46987765089, 106848.0, 107089.46455267751, 107316.0, 10776.946115269373, 11083.833742493869, 11254.774904502028, 11353.829692554618, 11469.713257168638, 22767.0, 22954.540909181724, 23153.421164470685, 23153.88423057874, 23185.420364491023, 7482.625868706543, 7735.226477352332, 7741.729039483591, 7750.10873749526, 7820.921790782133, 4229.492460904711, 4422.159789639958, 4488.281874900065, 4555.726656400627, 4616.815327386934, 3546.6098729139776, 3654.2691461707764, 3668.724845636588, 3675.228202077594, 3679.5216621839527, 635.745701719316, 655.1810237203496, 658.0162656828072, 658.1444122640644, 658.4798009572385, 550.9256250406224, 602.16299343912, 625.8404106952698, 626.5050610018035, 638.8409315932761, 8021.639026243833, 8789.560521973875, 8828.42615230008, 9535.368747492077, 9547.809043819085, 554.1671194175802, 558.9383139848338, 639.7600899662626, 661.1273119482333, 667.8263651450658, 4031.858884939013, 4169.812358443816, 4242.5757424257945, 4519.706219095814, 4545.136424079415, 130388.0, 132038.33980830034, 134052.32973835067, 138394.61605384017, 139599.30200349135, 108066.45966770273, 117581.41209293899, 117673.4116329413, 119407.0, 121534.0, 53323.93352133013, 64083.0, 96514.03485965331, 96604.51697741466, 103381.48309258405, 9327.906720932975, 9383.624655013722, 9436.490161574115, 9450.621975120917, 9554.570044347882, 56221.8755624877, 57046.7147664259, 61973.0, 63642.68178659077, 64082.0, 84169.57915210385, 87895.0, 91066.0, 92288.53855730679, 93966.53016734873, 12285.360416150606, 12658.683532911751, 12834.55079072228, 13051.869481305066, 13082.953363731069, 14363.640908977359, 14388.28058597087, 14610.634734131732, 14740.926295368454, 15211.239438027833, 11054.613088541862, 13197.20816750998, 13210.933945330213, 13357.033525897476, 13606.591802245948, 13144.0799144059, 13301.534446294334, 13443.395047223094, 13612.93193534026, 14005.929970350084, 11832.881671183179, 12837.743245134858, 12896.567197666816, 13286.933565332112, 13425.79167874896, 10064.949675251577, 10961.287516311415, 11430.371329576621, 11500.309981401142, 11632.825507617223, 10889.945550272198, 11020.559177632806, 11024.338539687644, 11186.23600972018, 11334.60328888485], "value": 7566.896406108174, "unit": "score"}], "name": "dromaeo_css", "value": 7336.908623603117, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 112.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 146.0, 146.0, 143.0, 142.0, 143.0, 114.0, 145.0, 122.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 183.0, 182.0, 180.0, 182.0, 182.0, 179.0, 180.0], "value": 181.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 59.0, 87.0, 63.0, 59.0, 58.0, 60.0, 82.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 92.0, 93.0, 54.0, 71.0, 120.0, 95.0, 68.0, 90.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 85.0, 87.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 143.0, 92.0, 88.0, 92.0, 88.0, 89.0, 92.0, 84.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 55.0, 55.0, 57.0, 56.0, 55.0, 56.0, 56.0, 54.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 71.0, 72.0, 71.0, 69.0, 67.0, 71.0, 69.0, 68.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 86.0, 76.0, 95.0, 95.0, 107.0, 108.0, 107.0, 91.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 178.0, 142.0, 157.0, 156.0, 156.0, 151.0, 145.0, 183.0, 147.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 172.0, 170.0, 171.0, 170.0, 225.0, 238.0, 172.0, 172.0], "value": 183.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 71.0, 81.0, 85.0, 88.0, 75.0, 71.0, 68.0, 67.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1491.7, "alertThreshold": 2.0}]} 19:41:56 INFO - Return code: 0 19:41:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:41:56 INFO - # TBPL SUCCESS # 19:41:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:41:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:41:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:41:56 INFO - 2016-10-04 19:41:56.141 screenresolution[1670:10450] starting screenresolution argv=screenresolution get 19:41:56 INFO - 2016-10-04 19:41:56.145 screenresolution[1670:10450] Display 0: 1600x1200x32@60 19:41:56 INFO - 2016-10-04 19:41:56.151 screenresolution[1671:10453] starting screenresolution argv=screenresolution list 19:41:56 INFO - Available Modes on Display 0 19:41:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:41:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:41:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:41:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:41:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:41:56 INFO - Intel Iris: 19:41:56 INFO - Chipset Model: Intel Iris 19:41:56 INFO - Type: GPU 19:41:56 INFO - Bus: Built-In 19:41:56 INFO - VRAM (Dynamic, Max): 1536 MB 19:41:56 INFO - Vendor: Intel (0x8086) 19:41:56 INFO - Device ID: 0x0a2e 19:41:56 INFO - Revision ID: 0x0009 19:41:56 INFO - Displays: 19:41:56 INFO - Display: 19:41:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:41:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:41:56 INFO - Display Serial Number: 1600x1200 60 19:41:56 INFO - Main Display: Yes 19:41:56 INFO - Mirror: Off 19:41:56 INFO - Online: Yes 19:41:56 INFO - Rotation: Supported 19:41:56 INFO - Return code: 0 19:41:56 INFO - Running post-action listener: _resource_record_post_action 19:41:56 INFO - [mozharness: 2016-10-05 02:41:56.390211Z] Finished run-tests step (success) 19:41:56 INFO - Running post-run listener: _resource_record_post_run 19:41:56 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 33315840; Write bytes: 710637056; Read time: 320; Write time: 2189 19:41:56 INFO - TinderboxPrint: CPU usage
53.5% 19:41:56 INFO - TinderboxPrint: I/O read bytes / time
33,315,840 / 320 19:41:56 INFO - TinderboxPrint: I/O write bytes / time
710,637,056 / 2,189 19:41:56 INFO - TinderboxPrint: CPU idle
2,257.5 (46.5%) 19:41:56 INFO - TinderboxPrint: CPU system
971.8 (20.0%) 19:41:56 INFO - TinderboxPrint: CPU user
1,628.3 (33.5%) 19:41:56 INFO - TinderboxPrint: Swap in / out
436,617,216 / 0 19:41:56 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406104576; Write bytes: 401405952; Read time: 29797; Write time: 717 19:41:56 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29293568; Write bytes: 298343936; Read time: 291; Write time: 1439 19:41:56 INFO - Running post-run listener: _upload_blobber_files 19:41:56 INFO - Blob upload gear active. 19:41:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:41:56 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 19:41:56 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'] 19:41:56 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 19:41:57 INFO - (blobuploader) - INFO - Open directory for files ... 19:41:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:41:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:41:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:41:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:41:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:41:58 INFO - (blobuploader) - INFO - Done attempting. 19:41:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:41:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:41:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:41:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:41:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:41:59 INFO - (blobuploader) - INFO - Done attempting. 19:41:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:41:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:41:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:01 INFO - (blobuploader) - INFO - Done attempting. 19:42:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:42:01 INFO - Return code: 0 19:42:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:42:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:42:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48636dd35993c4567c57ead6b503de14876063924880a6467617a409d8f2c85b68a487d665d6c889da7e817b3f0e94c2fefe7f7be8f584b67aea082c54ff03e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0547884db8def97ad00ed7fbc823010817064f6f81add7510d8dbedf987abb8fa5d40f258c5f5f5ffc4a8a955d382fc0dcc765a7374d849a55d57a914a76009d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c13f646350068b564e8509498fba1946d2748871ef631ceb102261af23c9302dfe69997bd0eb361df71c009d5aacc4f592495c31820c8168b34c65e2321582"} 19:42:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:42:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:42:01 INFO - Contents: 19:42:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48636dd35993c4567c57ead6b503de14876063924880a6467617a409d8f2c85b68a487d665d6c889da7e817b3f0e94c2fefe7f7be8f584b67aea082c54ff03e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0547884db8def97ad00ed7fbc823010817064f6f81add7510d8dbedf987abb8fa5d40f258c5f5f5ffc4a8a955d382fc0dcc765a7374d849a55d57a914a76009d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c13f646350068b564e8509498fba1946d2748871ef631ceb102261af23c9302dfe69997bd0eb361df71c009d5aacc4f592495c31820c8168b34c65e2321582"} 19:42:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:42:01 INFO - Copying logs to upload dir... 19:42:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:42:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.077559 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-10-04 19:42:02.034870) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 19:42:02.038630) ========= 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.WDxdMeuLto/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.Tn5r6USOeK/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48636dd35993c4567c57ead6b503de14876063924880a6467617a409d8f2c85b68a487d665d6c889da7e817b3f0e94c2fefe7f7be8f584b67aea082c54ff03e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0547884db8def97ad00ed7fbc823010817064f6f81add7510d8dbedf987abb8fa5d40f258c5f5f5ffc4a8a955d382fc0dcc765a7374d849a55d57a914a76009d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c13f646350068b564e8509498fba1946d2748871ef631ceb102261af23c9302dfe69997bd0eb361df71c009d5aacc4f592495c31820c8168b34c65e2321582"} build_url:https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48636dd35993c4567c57ead6b503de14876063924880a6467617a409d8f2c85b68a487d665d6c889da7e817b3f0e94c2fefe7f7be8f584b67aea082c54ff03e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0547884db8def97ad00ed7fbc823010817064f6f81add7510d8dbedf987abb8fa5d40f258c5f5f5ffc4a8a955d382fc0dcc765a7374d849a55d57a914a76009d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c13f646350068b564e8509498fba1946d2748871ef631ceb102261af23c9302dfe69997bd0eb361df71c009d5aacc4f592495c31820c8168b34c65e2321582"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 19:42:02.073189) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:42:02.073627) ========= 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.WDxdMeuLto/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.Tn5r6USOeK/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:42:02.122165) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 19:42:02.122487) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 19:42:02.122838) ========= ========= Total master_lag: 0.20 =========