builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-002 starttime: 1483050122.06 results: success (0) buildid: 20161229123011 builduid: c9efbec3fd5c4ac292b4d21f9a700ae1 revision: 1539be3e8e5b74e6a69d880765b2d7166f38599c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.065291) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.065720) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.066022) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.080798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.081093) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 14:22:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.1M=0s 2016-12-29 14:22:02 (36.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106711 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.201405) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.201703) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017561 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:02.250742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 14:22:02.251078) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1539be3e8e5b74e6a69d880765b2d7166f38599c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1539be3e8e5b74e6a69d880765b2d7166f38599c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 14:22:02,782 truncating revision to first 12 chars 2016-12-29 14:22:02,782 Setting DEBUG logging. 2016-12-29 14:22:02,782 attempt 1/10 2016-12-29 14:22:02,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1539be3e8e5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 14:22:03,140 unpacking tar archive at: mozilla-inbound-1539be3e8e5b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.272784 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 14:22:03.538512) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:03.538833) ========= script_repo_revision: 1539be3e8e5b74e6a69d880765b2d7166f38599c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:03.539268) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:03.539586) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 14:22:03.548600) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-29 14:22:03.548873) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:22:04 INFO - MultiFileLogger online at 20161229 14:22:04 in /builds/slave/test 14:22:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 14:22:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:22:04 INFO - {'append_to_log': False, 14:22:04 INFO - 'base_work_dir': '/builds/slave/test', 14:22:04 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 14:22:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:22:04 INFO - 'branch': 'Mozilla-Inbound', 14:22:04 INFO - 'buildbot_json_path': 'buildprops.json', 14:22:04 INFO - 'config_files': ('talos/linux_config.py',), 14:22:04 INFO - 'default_actions': ('clobber', 14:22:04 INFO - 'read-buildbot-config', 14:22:04 INFO - 'download-and-extract', 14:22:04 INFO - 'populate-webroot', 14:22:04 INFO - 'create-virtualenv', 14:22:04 INFO - 'install', 14:22:04 INFO - 'run-tests'), 14:22:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:22:04 INFO - 'download_minidump_stackwalk': True, 14:22:04 INFO - 'download_symbols': 'ondemand', 14:22:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:22:04 INFO - 'tooltool.py': '/tools/tooltool.py', 14:22:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:22:04 INFO - '/tools/misc-python/virtualenv.py')}, 14:22:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:22:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:22:04 INFO - 'installer_path': 'installer.exe', 14:22:04 INFO - 'log_level': 'info', 14:22:04 INFO - 'log_name': 'talos', 14:22:04 INFO - 'log_to_console': True, 14:22:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:22:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:22:04 INFO - 'opt_config_files': (), 14:22:04 INFO - 'pip_index': False, 14:22:04 INFO - 'sps_profile': False, 14:22:04 INFO - 'sps_profile_interval': 0, 14:22:04 INFO - 'suite': 'dromaeojs-e10s', 14:22:04 INFO - 'system_bits': '32', 14:22:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:22:04 INFO - 'title': 'talos-linux64-ix-002', 14:22:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:22:04 INFO - 'use_talos_json': True, 14:22:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:22:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:22:04 INFO - 'work_dir': 'build'} 14:22:04 INFO - [mozharness: 2016-12-29 22:22:04.190604Z] Running clobber step. 14:22:04 INFO - Running pre-action listener: _resource_record_pre_action 14:22:04 INFO - Running main action method: clobber 14:22:04 INFO - rmtree: /builds/slave/test/build 14:22:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:22:04 INFO - Running post-action listener: _resource_record_post_action 14:22:04 INFO - [mozharness: 2016-12-29 22:22:04.667715Z] Finished clobber step (success) 14:22:04 INFO - [mozharness: 2016-12-29 22:22:04.667816Z] Running read-buildbot-config step. 14:22:04 INFO - Running pre-action listener: _resource_record_pre_action 14:22:04 INFO - Running main action method: read_buildbot_config 14:22:04 INFO - Using buildbot properties: 14:22:04 INFO - { 14:22:04 INFO - "project": "", 14:22:04 INFO - "product": "firefox", 14:22:04 INFO - "script_repo_revision": "production", 14:22:04 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 14:22:04 INFO - "repository": "", 14:22:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 14:22:04 INFO - "buildid": "20161229123011", 14:22:04 INFO - "pgo_build": "True", 14:22:04 INFO - "basedir": "/builds/slave/test", 14:22:04 INFO - "buildnumber": 2166, 14:22:04 INFO - "slavename": "talos-linux64-ix-002", 14:22:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:22:04 INFO - "platform": "ubuntu64_hw", 14:22:04 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 14:22:04 INFO - "branch": "mozilla-inbound", 14:22:04 INFO - "repo_path": "integration/mozilla-inbound", 14:22:04 INFO - "revision": "1539be3e8e5b74e6a69d880765b2d7166f38599c", 14:22:04 INFO - "stage_platform": "linux64-pgo", 14:22:04 INFO - "builduid": "c9efbec3fd5c4ac292b4d21f9a700ae1", 14:22:04 INFO - "slavebuilddir": "test" 14:22:04 INFO - } 14:22:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:22:04 INFO - Running post-action listener: _resource_record_post_action 14:22:04 INFO - [mozharness: 2016-12-29 22:22:04.670291Z] Finished read-buildbot-config step (success) 14:22:04 INFO - [mozharness: 2016-12-29 22:22:04.670382Z] Running download-and-extract step. 14:22:04 INFO - Running pre-action listener: _resource_record_pre_action 14:22:04 INFO - Running main action method: download_and_extract 14:22:04 INFO - mkdir: /builds/slave/test/build/tests 14:22:04 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')]} 14:22:04 INFO - trying https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:22:04 INFO - Downloading https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:22:05 INFO - Downloaded 1361 bytes. 14:22:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:22:05 INFO - Using the following test package requirements: 14:22:05 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:22:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:22:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:22:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'jsshell-linux-x86_64.zip'], 14:22:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:22:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:22:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:22:05 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:22:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:22:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:22:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:22:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:22:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:22:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:22:05 INFO - Fetch https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:22:06 INFO - Expected file size: 21834568 14:22:06 INFO - Obtained file size: 21834568 14:22:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:22:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:22:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:22:10 INFO - Fetch https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:22:10 INFO - Expected file size: 21834568 14:22:10 INFO - Obtained file size: 21834568 14:22:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:22:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:22:15 INFO - Fetch https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:22:16 INFO - Expected file size: 14409776 14:22:16 INFO - Obtained file size: 14409776 14: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')]} 14:22:16 INFO - trying https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:22:16 INFO - Downloading https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:22:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:22:17 INFO - Downloaded 61378927 bytes. 14:22:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:22:17 INFO - mkdir: /builds/slave/test/properties 14:22:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:22:17 INFO - Writing to file /builds/slave/test/properties/build_url 14:22:17 INFO - Contents: 14:22:17 INFO - build_url:https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:22:18 INFO - Running post-action listener: _resource_record_post_action 14:22:18 INFO - Running post-action listener: set_extra_try_arguments 14:22:18 INFO - [mozharness: 2016-12-29 22:22:18.607746Z] Finished download-and-extract step (success) 14:22:18 INFO - [mozharness: 2016-12-29 22:22:18.607974Z] Running populate-webroot step. 14:22:18 INFO - Running pre-action listener: _resource_record_pre_action 14:22:18 INFO - Running main action method: populate_webroot 14:22:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:22:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:22:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:22:18 INFO - '--tppagecycles', 14:22:18 INFO - '7'], 14:22:18 INFO - 'tests': ['tsvgm']}}, 14:22:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:22:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:22:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:22:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:22:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['damp', 'tps']}, 14:22:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'tests': ['damp', 'tps']}, 14:22:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['dromaeo_dom']}, 14:22:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:22:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['basic_compositor_video']}, 14:22:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:22:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['a11yr', 14:22:18 INFO - 'ts_paint', 14:22:18 INFO - 'tpaint', 14:22:18 INFO - 'sessionrestore', 14:22:18 INFO - 'sessionrestore_no_auto_restore', 14:22:18 INFO - 'tabpaint']}, 14:22:18 INFO - 'other-e10s': {'tests': ['a11yr', 14:22:18 INFO - 'ts_paint', 14:22:18 INFO - 'tpaint', 14:22:18 INFO - 'sessionrestore', 14:22:18 INFO - 'sessionrestore_no_auto_restore', 14:22:18 INFO - 'tabpaint']}, 14:22:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:22:18 INFO - 'ts_paint', 14:22:18 INFO - 'tpaint', 14:22:18 INFO - 'sessionrestore', 14:22:18 INFO - 'sessionrestore_no_auto_restore', 14:22:18 INFO - 'tabpaint']}, 14:22:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:22:18 INFO - 'ts_paint', 14:22:18 INFO - 'tpaint', 14:22:18 INFO - 'sessionrestore', 14:22:18 INFO - 'sessionrestore_no_auto_restore', 14:22:18 INFO - 'tabpaint']}, 14:22:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['a11yr', 14:22:18 INFO - 'ts_paint', 14:22:18 INFO - 'tpaint', 14:22:18 INFO - 'sessionrestore', 14:22:18 INFO - 'sessionrestore_no_auto_restore', 14:22:18 INFO - 'tabpaint']}, 14:22:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['a11yr', 14:22:18 INFO - 'ts_paint', 14:22:18 INFO - 'tpaint', 14:22:18 INFO - 'sessionrestore', 14:22:18 INFO - 'sessionrestore_no_auto_restore', 14:22:18 INFO - 'tabpaint']}, 14:22:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['tsvgx', 14:22:18 INFO - 'tsvgr_opacity', 14:22:18 INFO - 'tart', 14:22:18 INFO - 'tscrollx', 14:22:18 INFO - 'cart', 14:22:18 INFO - 'tsvg_static']}, 14:22:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:22:18 INFO - 'tsvgr_opacity', 14:22:18 INFO - 'tart', 14:22:18 INFO - 'tscrollx', 14:22:18 INFO - 'cart', 14:22:18 INFO - 'tsvg_static']}, 14:22:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'talos_options': ['--disable-e10s'], 14:22:18 INFO - 'tests': ['tp5o']}, 14:22:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:22:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'talos_options': ['--disable-e10s', 14:22:18 INFO - '--xperf_path', 14:22:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:18 INFO - 'tests': ['tp5n']}, 14:22:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:22:18 INFO - 'talos_options': ['--xperf_path', 14:22:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:18 INFO - 'tests': ['tp5n']}}, 14:22:18 INFO - 'talos.zip': {'path': '', 14:22:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:22:18 INFO - Running post-action listener: _resource_record_post_action 14:22:18 INFO - [mozharness: 2016-12-29 22:22:18.629392Z] Finished populate-webroot step (success) 14:22:18 INFO - [mozharness: 2016-12-29 22:22:18.629481Z] Running create-virtualenv step. 14:22:18 INFO - Running pre-action listener: _resource_record_pre_action 14:22:18 INFO - Running main action method: create_virtualenv 14:22:18 INFO - Creating virtualenv /builds/slave/test/build/venv 14:22:18 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 14:22:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:22:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:22:18 INFO - Using real prefix '/usr' 14:22:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:22:19 INFO - Installing distribute.............................................................................................................................................................................................done. 14:22:21 INFO - Installing pip.................done. 14:22:21 INFO - Return code: 0 14:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:22:21 INFO - Reading from file tmpfile_stdout 14:22:21 INFO - Output received: 14:22:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:22:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:22:21 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')]} 14:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:21 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')]} 14:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:21 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 14:22:21 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 14:22:21 INFO - Using env: {'DISPLAY': ':0', 14:22:21 INFO - 'HOME': '/home/cltbld', 14:22:21 INFO - 'LANG': 'en_US.UTF-8', 14:22:21 INFO - 'LANGUAGE': 'en_US:en', 14:22:21 INFO - 'LOGNAME': 'cltbld', 14:22:21 INFO - 'MAIL': '/var/mail/cltbld', 14:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:21 INFO - 'MOZ_NO_REMOTE': '1', 14:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:21 INFO - 'NO_EM_RESTART': '1', 14:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:21 INFO - 'PWD': '/builds/slave/test', 14:22:21 INFO - 'SHELL': '/bin/bash', 14:22:21 INFO - 'SHLVL': '1', 14:22:21 INFO - 'TERM': 'linux', 14:22:21 INFO - 'TMOUT': '86400', 14:22:21 INFO - 'USER': 'cltbld', 14:22:21 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:21 INFO - '_': '/tools/buildbot/bin/python'} 14:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:21 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 14:22:21 INFO - Cleaning up... 14:22:21 INFO - Return code: 0 14:22:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:22:21 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')]} 14:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:21 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')]} 14:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:21 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 14:22:21 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 14:22:21 INFO - Using env: {'DISPLAY': ':0', 14:22:21 INFO - 'HOME': '/home/cltbld', 14:22:21 INFO - 'LANG': 'en_US.UTF-8', 14:22:21 INFO - 'LANGUAGE': 'en_US:en', 14:22:21 INFO - 'LOGNAME': 'cltbld', 14:22:21 INFO - 'MAIL': '/var/mail/cltbld', 14:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:21 INFO - 'MOZ_NO_REMOTE': '1', 14:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:21 INFO - 'NO_EM_RESTART': '1', 14:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:21 INFO - 'PWD': '/builds/slave/test', 14:22:21 INFO - 'SHELL': '/bin/bash', 14:22:21 INFO - 'SHLVL': '1', 14:22:21 INFO - 'TERM': 'linux', 14:22:21 INFO - 'TMOUT': '86400', 14:22:21 INFO - 'USER': 'cltbld', 14:22:21 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:21 INFO - '_': '/tools/buildbot/bin/python'} 14:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:21 INFO - Downloading/unpacking psutil>=3.1.1 14:22:21 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 14:22:21 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 14:22:21 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 14:22:21 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 14:22:21 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 14:22:21 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 14:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:22:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:23 INFO - Installing collected packages: psutil 14:22:23 INFO - Running setup.py install for psutil 14:22:24 INFO - building 'psutil._psutil_linux' extension 14:22:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:22:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:22:24 INFO - building 'psutil._psutil_posix' extension 14:22:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:22:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:22:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:24 INFO - Successfully installed psutil 14:22:24 INFO - Cleaning up... 14:22:24 INFO - Return code: 0 14:22:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:22:24 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')]} 14:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:24 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')]} 14:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:24 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:24 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 14:22:24 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 14:22:24 INFO - Using env: {'DISPLAY': ':0', 14:22:24 INFO - 'HOME': '/home/cltbld', 14:22:24 INFO - 'LANG': 'en_US.UTF-8', 14:22:24 INFO - 'LANGUAGE': 'en_US:en', 14:22:24 INFO - 'LOGNAME': 'cltbld', 14:22:24 INFO - 'MAIL': '/var/mail/cltbld', 14:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:24 INFO - 'MOZ_NO_REMOTE': '1', 14:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:24 INFO - 'NO_EM_RESTART': '1', 14:22:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:24 INFO - 'PWD': '/builds/slave/test', 14:22:24 INFO - 'SHELL': '/bin/bash', 14:22:24 INFO - 'SHLVL': '1', 14:22:24 INFO - 'TERM': 'linux', 14:22:24 INFO - 'TMOUT': '86400', 14:22:24 INFO - 'USER': 'cltbld', 14:22:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:24 INFO - '_': '/tools/buildbot/bin/python'} 14:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:22:24 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 14:22:24 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 14:22:24 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 14:22:24 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 14:22:24 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 14:22:24 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 14:22:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:22:26 INFO - Installing collected packages: mozsystemmonitor 14:22:26 INFO - Running setup.py install for mozsystemmonitor 14:22:26 INFO - Successfully installed mozsystemmonitor 14:22:26 INFO - Cleaning up... 14:22:26 INFO - Return code: 0 14:22:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:22: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')]} 14:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22: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')]} 14:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22: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', '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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:22: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 jsonschema==2.5.1 14:22:26 INFO - Using env: {'DISPLAY': ':0', 14:22:26 INFO - 'HOME': '/home/cltbld', 14:22:26 INFO - 'LANG': 'en_US.UTF-8', 14:22:26 INFO - 'LANGUAGE': 'en_US:en', 14:22:26 INFO - 'LOGNAME': 'cltbld', 14:22:26 INFO - 'MAIL': '/var/mail/cltbld', 14:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:26 INFO - 'MOZ_NO_REMOTE': '1', 14:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:26 INFO - 'NO_EM_RESTART': '1', 14:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:26 INFO - 'PWD': '/builds/slave/test', 14:22:26 INFO - 'SHELL': '/bin/bash', 14:22:26 INFO - 'SHLVL': '1', 14:22:26 INFO - 'TERM': 'linux', 14:22:26 INFO - 'TMOUT': '86400', 14:22:26 INFO - 'USER': 'cltbld', 14:22:26 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:26 INFO - '_': '/tools/buildbot/bin/python'} 14:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:27 INFO - Downloading/unpacking jsonschema==2.5.1 14:22:27 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 14:22:27 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 14:22:27 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 14:22:27 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 14:22:27 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 14:22:27 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 14:22:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:22:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22:29 INFO - Downloading functools32-3.2.3-2.tar.gz 14:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:22:29 INFO - warning: no files found matching '*.txt' 14:22:29 INFO - no previously-included directories found matching 'build' 14:22:29 INFO - no previously-included directories found matching 'dist' 14:22:29 INFO - no previously-included directories found matching '.git*' 14:22:29 INFO - Installing collected packages: jsonschema, functools32 14:22:29 INFO - Running setup.py install for functools32 14:22:29 INFO - warning: no files found matching '*.txt' 14:22:29 INFO - no previously-included directories found matching 'build' 14:22:29 INFO - no previously-included directories found matching 'dist' 14:22:29 INFO - no previously-included directories found matching '.git*' 14:22:29 INFO - Successfully installed jsonschema functools32 14:22:29 INFO - Cleaning up... 14:22:29 INFO - Return code: 0 14:22:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:22:29 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')]} 14:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:29 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')]} 14:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:29 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:29 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 14:22:29 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 14:22:29 INFO - Using env: {'DISPLAY': ':0', 14:22:29 INFO - 'HOME': '/home/cltbld', 14:22:29 INFO - 'LANG': 'en_US.UTF-8', 14:22:29 INFO - 'LANGUAGE': 'en_US:en', 14:22:29 INFO - 'LOGNAME': 'cltbld', 14:22:29 INFO - 'MAIL': '/var/mail/cltbld', 14:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:29 INFO - 'MOZ_NO_REMOTE': '1', 14:22:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:29 INFO - 'NO_EM_RESTART': '1', 14:22:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:29 INFO - 'PWD': '/builds/slave/test', 14:22:29 INFO - 'SHELL': '/bin/bash', 14:22:29 INFO - 'SHLVL': '1', 14:22:29 INFO - 'TERM': 'linux', 14:22:29 INFO - 'TMOUT': '86400', 14:22:29 INFO - 'USER': 'cltbld', 14:22:29 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:29 INFO - '_': '/tools/buildbot/bin/python'} 14:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:22:29 INFO - Cleaning up... 14:22:29 INFO - Return code: 0 14:22:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:22:29 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')]} 14:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:29 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')]} 14:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:29 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:29 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 14:22:29 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 14:22:29 INFO - Using env: {'DISPLAY': ':0', 14:22:29 INFO - 'HOME': '/home/cltbld', 14:22:29 INFO - 'LANG': 'en_US.UTF-8', 14:22:29 INFO - 'LANGUAGE': 'en_US:en', 14:22:29 INFO - 'LOGNAME': 'cltbld', 14:22:29 INFO - 'MAIL': '/var/mail/cltbld', 14:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:29 INFO - 'MOZ_NO_REMOTE': '1', 14:22:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:29 INFO - 'NO_EM_RESTART': '1', 14:22:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:29 INFO - 'PWD': '/builds/slave/test', 14:22:29 INFO - 'SHELL': '/bin/bash', 14:22:29 INFO - 'SHLVL': '1', 14:22:29 INFO - 'TERM': 'linux', 14:22:29 INFO - 'TMOUT': '86400', 14:22:29 INFO - 'USER': 'cltbld', 14:22:29 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:29 INFO - '_': '/tools/buildbot/bin/python'} 14:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:30 INFO - Downloading/unpacking blobuploader==1.2.4 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22:32 INFO - Downloading blobuploader-1.2.4.tar.gz 14:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:22:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:22:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 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 14:22:32 INFO - Downloading docopt-0.6.1.tar.gz 14:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:32 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:32 INFO - Running setup.py install for blobuploader 14:22:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:22:32 INFO - Running setup.py install for requests 14:22:33 INFO - Running setup.py install for docopt 14:22:33 INFO - Successfully installed blobuploader requests docopt 14:22:33 INFO - Cleaning up... 14:22:33 INFO - Return code: 0 14:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:33 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')]} 14:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:33 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')]} 14:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:33 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:33 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 14:22:33 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 14:22:33 INFO - Using env: {'DISPLAY': ':0', 14:22:33 INFO - 'HOME': '/home/cltbld', 14:22:33 INFO - 'LANG': 'en_US.UTF-8', 14:22:33 INFO - 'LANGUAGE': 'en_US:en', 14:22:33 INFO - 'LOGNAME': 'cltbld', 14:22:33 INFO - 'MAIL': '/var/mail/cltbld', 14:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:33 INFO - 'MOZ_NO_REMOTE': '1', 14:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:33 INFO - 'NO_EM_RESTART': '1', 14:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:33 INFO - 'PWD': '/builds/slave/test', 14:22:33 INFO - 'SHELL': '/bin/bash', 14:22:33 INFO - 'SHLVL': '1', 14:22:33 INFO - 'TERM': 'linux', 14:22:33 INFO - 'TMOUT': '86400', 14:22:33 INFO - 'USER': 'cltbld', 14:22:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:33 INFO - '_': '/tools/buildbot/bin/python'} 14:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:33 INFO - Running setup.py (path:/tmp/pip-c9WAHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:33 INFO - Running setup.py (path:/tmp/pip-4pSTFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:33 INFO - Running setup.py (path:/tmp/pip-dOkA_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:33 INFO - Running setup.py (path:/tmp/pip-5Xcit_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:34 INFO - Running setup.py (path:/tmp/pip-99RgJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:34 INFO - Running setup.py (path:/tmp/pip-jjYNNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:34 INFO - Running setup.py (path:/tmp/pip-LCrW9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:34 INFO - Running setup.py (path:/tmp/pip-IAgphu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:34 INFO - Running setup.py (path:/tmp/pip-6O4Fgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:34 INFO - Running setup.py (path:/tmp/pip-nDmCyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:34 INFO - Running setup.py (path:/tmp/pip-IM6R69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:34 INFO - Running setup.py (path:/tmp/pip-HqsQHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:34 INFO - Running setup.py (path:/tmp/pip-AT0W3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:34 INFO - Running setup.py (path:/tmp/pip-BxfgM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:34 INFO - Running setup.py (path:/tmp/pip-fD8z5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:34 INFO - Running setup.py (path:/tmp/pip-DFwIV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:34 INFO - Running setup.py (path:/tmp/pip-Qpf1q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:22:34 INFO - Running setup.py install for manifestparser 14:22:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Running setup.py install for mozcrash 14:22:35 INFO - Running setup.py install for mozdebug 14:22:35 INFO - Running setup.py install for mozdevice 14:22:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Running setup.py install for mozfile 14:22:35 INFO - Running setup.py install for mozhttpd 14:22:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Running setup.py install for mozinfo 14:22:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Running setup.py install for mozInstall 14:22:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:22:35 INFO - Running setup.py install for mozleak 14:22:35 INFO - Running setup.py install for mozlog 14:22:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for moznetwork 14:22:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozprocess 14:22:36 INFO - Running setup.py install for mozprofile 14:22:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozrunner 14:22:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozscreenshot 14:22:36 INFO - Running setup.py install for moztest 14:22:36 INFO - Running setup.py install for mozversion 14:22:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:22:36 INFO - Cleaning up... 14:22:36 INFO - Return code: 0 14:22:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22: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')]} 14:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22: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')]} 14:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22: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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22: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 14:22: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 14:22:36 INFO - Using env: {'DISPLAY': ':0', 14:22:36 INFO - 'HOME': '/home/cltbld', 14:22:36 INFO - 'LANG': 'en_US.UTF-8', 14:22:36 INFO - 'LANGUAGE': 'en_US:en', 14:22:36 INFO - 'LOGNAME': 'cltbld', 14:22:36 INFO - 'MAIL': '/var/mail/cltbld', 14:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:36 INFO - 'MOZ_NO_REMOTE': '1', 14:22:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:36 INFO - 'NO_EM_RESTART': '1', 14:22:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:36 INFO - 'PWD': '/builds/slave/test', 14:22:36 INFO - 'SHELL': '/bin/bash', 14:22:36 INFO - 'SHLVL': '1', 14:22:36 INFO - 'TERM': 'linux', 14:22:36 INFO - 'TMOUT': '86400', 14:22:36 INFO - 'USER': 'cltbld', 14:22:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:36 INFO - '_': '/tools/buildbot/bin/python'} 14:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:37 INFO - Running setup.py (path:/tmp/pip-1R9_ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:37 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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:37 INFO - Running setup.py (path:/tmp/pip-LTSZ11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:37 INFO - Running setup.py (path:/tmp/pip-3ME0jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:37 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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:37 INFO - Running setup.py (path:/tmp/pip-ZW9Ksb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:37 INFO - Running setup.py (path:/tmp/pip-WGbr_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:37 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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:37 INFO - Running setup.py (path:/tmp/pip-WNlhae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:37 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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:37 INFO - Running setup.py (path:/tmp/pip-l9zPA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:37 INFO - Running setup.py (path:/tmp/pip-iS11Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:37 INFO - Running setup.py (path:/tmp/pip-wYBoGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:37 INFO - Running setup.py (path:/tmp/pip-68uKHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:37 INFO - Running setup.py (path:/tmp/pip-uoOlSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:37 INFO - Running setup.py (path:/tmp/pip-pFzKab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:37 INFO - Running setup.py (path:/tmp/pip-ECxixr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22: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)) 14:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:38 INFO - Running setup.py (path:/tmp/pip-LJDTb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:38 INFO - Running setup.py (path:/tmp/pip-jeAbNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:38 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)) 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:38 INFO - Running setup.py (path:/tmp/pip-PgXVKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:38 INFO - Running setup.py (path:/tmp/pip-3PAfkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22: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)) 14:22: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22: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)) 14:22: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22:40 INFO - Downloading blessings-1.6.tar.gz 14:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:22:40 INFO - Installing collected packages: blessings 14:22:40 INFO - Running setup.py install for blessings 14:22:40 INFO - Successfully installed blessings 14:22:40 INFO - Cleaning up... 14:22:40 INFO - Return code: 0 14:22:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:40 INFO - Reading from file tmpfile_stdout 14:22:40 INFO - Current package versions: 14:22:40 INFO - argparse == 1.2.1 14:22:40 INFO - blessings == 1.6 14:22:40 INFO - blobuploader == 1.2.4 14:22:40 INFO - docopt == 0.6.1 14:22:40 INFO - functools32 == 3.2.3-2 14:22:40 INFO - jsonschema == 2.5.1 14:22:40 INFO - manifestparser == 1.1 14:22:40 INFO - mozInstall == 1.12 14:22:40 INFO - mozcrash == 1.0 14:22:40 INFO - mozdebug == 0.1 14:22:40 INFO - mozdevice == 0.49 14:22:40 INFO - mozfile == 1.2 14:22:40 INFO - mozhttpd == 0.7 14:22:40 INFO - mozinfo == 0.9 14:22:40 INFO - mozleak == 0.1 14:22:40 INFO - mozlog == 3.4 14:22:40 INFO - moznetwork == 0.27 14:22:40 INFO - mozprocess == 0.23 14:22:40 INFO - mozprofile == 0.28 14:22:40 INFO - mozrunner == 6.13 14:22:40 INFO - mozscreenshot == 0.1 14:22:40 INFO - mozsystemmonitor == 0.3 14:22:40 INFO - moztest == 0.8 14:22:40 INFO - mozversion == 1.4 14:22:40 INFO - psutil == 3.1.1 14:22:40 INFO - requests == 1.2.3 14:22:40 INFO - wsgiref == 0.1.2 14:22:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:40 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')]} 14:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:40 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')]} 14:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:40 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:40 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 14:22:40 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 14:22:40 INFO - Using env: {'DISPLAY': ':0', 14:22:40 INFO - 'HOME': '/home/cltbld', 14:22:40 INFO - 'LANG': 'en_US.UTF-8', 14:22:40 INFO - 'LANGUAGE': 'en_US:en', 14:22:40 INFO - 'LOGNAME': 'cltbld', 14:22:40 INFO - 'MAIL': '/var/mail/cltbld', 14:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:40 INFO - 'MOZ_NO_REMOTE': '1', 14:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:40 INFO - 'NO_EM_RESTART': '1', 14:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:40 INFO - 'PWD': '/builds/slave/test', 14:22:40 INFO - 'SHELL': '/bin/bash', 14:22:40 INFO - 'SHLVL': '1', 14:22:40 INFO - 'TERM': 'linux', 14:22:40 INFO - 'TMOUT': '86400', 14:22:40 INFO - 'USER': 'cltbld', 14:22:40 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:40 INFO - '_': '/tools/buildbot/bin/python'} 14:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 INFO - Cleaning up... 14:22:40 INFO - Return code: 0 14:22:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:22:40 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')]} 14:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:40 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')]} 14:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22: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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d54480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22: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 14:22: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 14:22:41 INFO - Using env: {'DISPLAY': ':0', 14:22:41 INFO - 'HOME': '/home/cltbld', 14:22:41 INFO - 'LANG': 'en_US.UTF-8', 14:22:41 INFO - 'LANGUAGE': 'en_US:en', 14:22:41 INFO - 'LOGNAME': 'cltbld', 14:22:41 INFO - 'MAIL': '/var/mail/cltbld', 14:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:41 INFO - 'MOZ_NO_REMOTE': '1', 14:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:41 INFO - 'NO_EM_RESTART': '1', 14:22:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:41 INFO - 'PWD': '/builds/slave/test', 14:22:41 INFO - 'SHELL': '/bin/bash', 14:22:41 INFO - 'SHLVL': '1', 14:22:41 INFO - 'TERM': 'linux', 14:22:41 INFO - 'TMOUT': '86400', 14:22:41 INFO - 'USER': 'cltbld', 14:22:41 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:41 INFO - '_': '/tools/buildbot/bin/python'} 14:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:22:41 INFO - Cleaning up... 14:22:41 INFO - Return code: 0 14:22:41 INFO - Running post-action listener: _resource_record_post_action 14:22:41 INFO - Running post-action listener: _start_resource_monitoring 14:22:41 INFO - Starting resource monitoring. 14:22:41 INFO - [mozharness: 2016-12-29 22:22:41.260378Z] Finished create-virtualenv step (success) 14:22:41 INFO - [mozharness: 2016-12-29 22:22:41.260881Z] Running install step. 14:22:41 INFO - Running pre-action listener: _resource_record_pre_action 14:22:41 INFO - Running main action method: install 14:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:41 INFO - Reading from file tmpfile_stdout 14:22:41 INFO - Detecting whether we're running mozinstall >=1.0... 14:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:22:41 INFO - Reading from file tmpfile_stdout 14:22:41 INFO - Output received: 14:22:41 INFO - Usage: mozinstall [options] installer 14:22:41 INFO - Options: 14:22:41 INFO - -h, --help show this help message and exit 14:22:41 INFO - -d DEST, --destination=DEST 14:22:41 INFO - Directory to install application into. [default: 14:22:41 INFO - "/builds/slave/test"] 14:22:41 INFO - --app=APP Application being installed. [default: firefox] 14:22:41 INFO - mkdir: /builds/slave/test/build/application 14:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:22:58 INFO - Reading from file tmpfile_stdout 14:22:58 INFO - Output received: 14:22:58 INFO - /builds/slave/test/build/application/firefox/firefox 14:22:58 INFO - Running post-action listener: _resource_record_post_action 14:22:58 INFO - [mozharness: 2016-12-29 22:22:58.938498Z] Finished install step (success) 14:22:58 INFO - [mozharness: 2016-12-29 22:22:58.938742Z] Running run-tests step. 14:22:58 INFO - Running pre-action listener: _resource_record_pre_action 14:22:58 INFO - Running main action method: run_tests 14:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:22:58 INFO - Python 2.7.3 14:22:58 INFO - Return code: 0 14:22:58 INFO - grabbing minidump binary from tooltool 14:22:58 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')]} 14:22:58 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/linux64/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 0x1d51820>, '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 0x1d52520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d529b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:22:58 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:22:58 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/linux64/releng.manifest -o -c /builds/tooltool_cache 14:22:58 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:22:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:22:59 INFO - Return code: 0 14:22:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:22:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:22:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:22:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:22:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:22:59 INFO - Using env: {'DISPLAY': ':0', 14:22:59 INFO - 'HOME': '/home/cltbld', 14:22:59 INFO - 'LANG': 'en_US.UTF-8', 14:22:59 INFO - 'LANGUAGE': 'en_US:en', 14:22:59 INFO - 'LOGNAME': 'cltbld', 14:22:59 INFO - 'MAIL': '/var/mail/cltbld', 14:22:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:22:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:59 INFO - 'MOZ_NO_REMOTE': '1', 14:22:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:22:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:59 INFO - 'NO_EM_RESTART': '1', 14:22:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:59 INFO - 'PWD': '/builds/slave/test', 14:22:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:22:59 INFO - 'SHELL': '/bin/bash', 14:22:59 INFO - 'SHLVL': '1', 14:22:59 INFO - 'TERM': 'linux', 14:22:59 INFO - 'TMOUT': '86400', 14:22:59 INFO - 'USER': 'cltbld', 14:22:59 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252', 14:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:59 INFO - '_': '/tools/buildbot/bin/python'} 14:22:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:22:59 INFO - mozversion application_buildid: 20161229123011 14:22:59 INFO - mozversion application_changeset: 1539be3e8e5b74e6a69d880765b2d7166f38599c 14:22:59 INFO - mozversion application_display_name: Nightly 14:22:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:22:59 INFO - mozversion application_name: Firefox 14:22:59 INFO - mozversion application_remotingname: firefox 14:22:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:22:59 INFO - mozversion application_vendor: Mozilla 14:22:59 INFO - mozversion application_version: 53.0a1 14:22:59 INFO - mozversion platform_buildid: 20161229123011 14:22:59 INFO - mozversion platform_changeset: 1539be3e8e5b74e6a69d880765b2d7166f38599c 14:22:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:22:59 INFO - mozversion platform_version: 53.0a1 14:22:59 INFO - using testdate: 1483050179 14:22:59 INFO - actual date: 1483050179 14:22:59 INFO - starting webserver on 'localhost:51699' 14:22:59 INFO - SUITE-START | Running 2 tests 14:22:59 INFO - TEST-START | dromaeo_css 14:22:59 INFO - operating with platform_type : linux_ 14:22:59 INFO - Initialising browser for dromaeo_css test... 14:22:59 INFO - TEST-INFO | started process 22167 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQlxK7t/profile http://localhost:51699/getInfo.html) 14:22:59 INFO - PROCESS | 22167 | 1483050179695 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 14:23:03 INFO - PROCESS | 22167 | __metrics Screen width/height:1600/1200 14:23:03 INFO - PROCESS | 22167 | colorDepth:24 14:23:03 INFO - PROCESS | 22167 | Browser inner width/height: 1024/697 14:23:03 INFO - PROCESS | 22167 | __metrics 14:23:04 ERROR - PROCESS | 22167 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 14:23:04 INFO - TEST-INFO | 22167: exit 0 14:23:04 INFO - Browser initialized. 14:23:04 INFO - Running cycle 1/1 for dromaeo_css test... 14:23:04 INFO - TEST-INFO | started process 22286 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQlxK7t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:16 INFO - PROCESS | 22286 | RSS: Main: 154222592 14:23:16 INFO - PROCESS | 22286 | 14:26:16 INFO - PROCESS | 22286 | Cycle 1(1): loaded http://localhost:51699/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51699/tests/dromaeo/cssquery-ext.html) 14:26:18 INFO - PROCESS | 22286 | RSS: Main: 162693120 14:26:18 INFO - PROCESS | 22286 | 14:28:05 INFO - PROCESS | 22286 | ************************* 14:28:05 INFO - PROCESS | 22286 | A coding exception was thrown and uncaught in a Task. 14:28:05 INFO - PROCESS | 22286 | 14:28:05 INFO - PROCESS | 22286 | Full message: ReferenceError: fetch is not defined 14:28:05 INFO - PROCESS | 22286 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 14:28:05 INFO - PROCESS | 22286 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 14:28:05 INFO - PROCESS | 22286 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 14:28:05 INFO - PROCESS | 22286 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:28:05 INFO - PROCESS | 22286 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:28:05 INFO - PROCESS | 22286 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:28:05 INFO - PROCESS | 22286 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 14:28:05 INFO - PROCESS | 22286 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:28:05 INFO - PROCESS | 22286 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:28:05 INFO - PROCESS | 22286 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:28:05 INFO - PROCESS | 22286 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 14:28:05 INFO - PROCESS | 22286 | 14:28:05 INFO - PROCESS | 22286 | ************************* 14:29:20 INFO - PROCESS | 22286 | Cycle 1(1): loaded http://localhost:51699/tests/dromaeo/cssquery-ext.html (next: http://localhost:51699/tests/dromaeo/cssquery-jquery.html) 14:29:21 INFO - PROCESS | 22286 | RSS: Main: 159571968 14:29:21 INFO - PROCESS | 22286 | 14:32:23 INFO - PROCESS | 22286 | Cycle 1(1): loaded http://localhost:51699/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51699/tests/dromaeo/cssquery-mootools.html) 14:32:23 INFO - PROCESS | 22286 | RSS: Main: 166543360 14:32:23 INFO - PROCESS | 22286 | 14:35:25 INFO - PROCESS | 22286 | Cycle 1(1): loaded http://localhost:51699/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51699/tests/dromaeo/cssquery-prototype.html) 14:35:26 INFO - PROCESS | 22286 | RSS: Main: 160141312 14:35:26 INFO - PROCESS | 22286 | 14:38:29 INFO - PROCESS | 22286 | Cycle 1(1): loaded http://localhost:51699/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51699/tests/dromaeo/cssquery-yui.html) 14:38:29 INFO - PROCESS | 22286 | RSS: Main: 165396480 14:38:29 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | Cycle 1(1): loaded http://localhost:51699/tests/dromaeo/cssquery-yui.html (next: http://localhost:51699/tests/dromaeo/cssquery-dojo.html) 14:41:32 INFO - PROCESS | 22286 | RSS: Main: 163766272 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | __start_tp_report 14:41:32 INFO - PROCESS | 22286 | _x_x_mozilla_page_load 14:41:32 INFO - PROCESS | 22286 | _x_x_mozilla_page_load_details 14:41:32 INFO - PROCESS | 22286 | |i|pagename|runs| 14:41:32 INFO - PROCESS | 22286 | |0;dojo.html;3076.615423072116;3145.8112513249207;3151.653318135006;3154.9053528394147;3191.3298207376465;5193.818216362428;5196.688198708081;5244.05606990742;5302.78788848446;5326.7070311132875;5263.631545791796;5292.655977361469;5359.5444387227135;5373.408925018254;5423.267858839065;4754.619630429566;4820.493848145929;4841.782119804618;4927.457979622239;4950.207966725325;4811;4844.031193761244;4891.241857512091;4894;4897.657163998521;4758.857234282977;4887.315775791392;4899.436564795023;4919.360483137187;4992.051510213048;4797.328374027639;4811;4855.247436647325;4873.439554451248;4927.6550641455115;4811.085893680224;4816.56650901417;4838.056578967103;4854.611631069506;4888.41339039314;5323.680579165224;5337.226102215196;5342.893142137135;5353.116735738598;5372.623916325859;5513.20058591506;5538.695371754552;5600.1599760035915;5644.040513112781;5661.518770904437;5715.2855893013375;5721.7711291548285;5745;5760.107183386582;5800.187973683687;5334.59990500714;5335.279737235501;5354.063038968165;5354.785808567653;5361.463853614647;5687.601867869293;5730.942690573124;5765.625234359753;5812.82561523155;5896.204012458349;5589.776408943678;5607.4112218217315;5608.887822243532;5668.404817494186;5672.858178545487;5687.971560142173;5692.857678557986;5732.0255556555485;5741.224934633771;5756.884862302731;5580.972095139579;5624.325080990226;5653.321601407857;5695.430456954271;5701.885962280731;5687.601867869293;5750.30996280449;5753.51095156908;5765.106408506688;5798.043322851682;4304.074623955865;4329.567043295645;4330.285502891988;4334.523202447728;4365.672574556921;3763.8870833875026;3771.773693578394;3792.6207379262405;3819.350710379242;3894.6105389460827;5461.42655021225;5461.453854614586;5464.426235245322;5520.503154716095;5536.806211782568;5713.885722285531;5731.0543760279925;5749.9425005750245;5750.568707346965;5771.047777116729;5312.442193569696;5339.439358867263;5346.198070289488;5469.288992430958;5478.890422191614;4714.858554243378;4746.59653929413;4747.335373047776;4779.235322348408;4787;5974.2233509643465;5983.910241346383;5986.431289027535;5990.73041713124;6009.429104235091;5569.19246709233;5582.637128586629;5667.716614169358;5758.107493338539;5777.3356064051495;5905.616134951214;5914.4381283777975;5914.497267732291;5918.437748413894;5919.911201331897;5530.225768392348;5573.8606534835335;5603.327600687943;5606.635568688022;5700.743466543932;5746.856328591819;5747.453991870766;5752.482276595127;5755.021646320052;5758.165066065313;5590.273264475755;5596.468335508121;5604.355499117653;5605.271314729059;5608.663480191201;5394.811181608625;5454.972725136349;5455.236266922555;5536.446355364431;5552.416996215501;5451.318585176853;5576.386597474356;5582.469665381782;5587.552995760248;5607.74765135578;5497.560195184456;5587.55299576041;5588.916166257591;5605.439456054362;5632.070708333079;5421.647592906448;5424.891502169856;5443.455654434525;5455.1271796512365;5455.699936503372 14:41:32 INFO - PROCESS | 22286 | |1;ext.html;535.9276497672814;571.7484306904962;578.2771535580524;611.6696983628838;635.9300476947537;38280.808595957016;42058;42101.78949105254;42265.505484287074;42301.15397692045;42503.78748106263;43310.35034474477;43312.78343608286;43316.78341608295;43381.566184338146;8567.70013049545;8828.779280517974;9952.154066904322;10315.277930544828;10361.274710770249;10164.339317944346;10195.286329956905;10224.028717271884;10232.89767102327;10264.281500294945;10258.846117308247;10262.794744105113;10270.948645256763;10327.483625818679;10344.534495947662;10220.23348248877;10226.590936362574;10272.229582781247;10299.588016479294;10309.226807989431;10049.598016079348;10079.244056695778;10083.546240419199;10107.49462526878;10112.848307275397;38605.806970964964;38773;38872.22255554873;38913.221735565414;39070.41394379087;3804.220134872372;3806.2577797329254;3867.59390264021;3915.6671682906704;3917.1578110706337;29664.25839354012;29868.55197172044;29996.700032999608;30004.69995300041;30794;4228.323468245097;4290.656747460227;4368.060866913629;4472.8881777955485;4493.9101217975785;319891;322297;322575.3553412422;322844;323543;51857;52022.21966670503;52189;52319.69200770011;52350.73824630929;53213;53535.732321338146;53604.19593706098;53752.73123634435;53903.730481347346;14228.28858557068;15816.76274855878;16000.919995399949;16002.519924402053;16033.11817850006;10925.726856828484;12179.939100304442;12446.25322480654;12514.687132821753;12688.49246030167;16774.161291935354;16818.831811681728;16823.224168012384;16833;16843;6747.426468750113;6777.728890844311;6859.691313890887;7081.89377159343;7112.146542414944;901.1484147480699;911.3165126155384;912.7672443526991;917.1424717888796;933.3699752666922;29880;30946.845265773976;30977.535336969962;31155;31408.685913141035;1059.4543809937888;1073.5652060915343;1077.6174458067273;1079.3523885668535;1090.8854570270166;15364.769528457082;15661.451849185223;16299.755503667455;16432.178391080397;16654.666906661798;356222;462105;464673;466594;467176;53380.46619533755;53612;53796.73101634467;54039.45960540423;54167.45832541703;1355.5526676197028;1372.2521225931891;1395.3441882315499;1413.4452102687167;1414.957551273443;27880.860595696893;28763.280917976383;29536.409271814875;29541.113766587037;29751.553726693684;45389.54610453853;46268.768656156506;46282.76858615685;46326.536734632224;46341.07317853557;418999;420553;420912;422490;424481;22722.318330450114;23082.769172308734;24270.87864560666;25161;25215.243542693748;40181.397279040255;40387.596124038384;40549;40615.3907691379;41036.58963410328;35282.64717352896;35459.64540354563;35652.28695426129;35781.64218357783;36046;34314;34487.82756086203;34528.48207276843;34792;35054.47418288779;34615.65384346225;34627;35046;35387.46918796271;35828.82085589555;30834.53748193734;31730.206744831565;31944.840275798473;32185.74699771087;32234.35531289408;23805.404864878517;24264.1507547235;24456.144034958692;24634.876825615756;24686 14:41:32 INFO - PROCESS | 22286 | |2;jquery.html;402.3421705511488;410.7679161273878;411.1714894487608;413.4645633904094;425.41293015638416;15866.444674436398;16450.506484805446;18103.913765174082;18768.530786730338;18865.434036978895;12099.818502722466;12829.101962862604;12840.0369972252;17206.311747530115;19409.805901940977;5113.769880355384;5146.382434107913;5304.787808487656;5327.893442131155;5340.946590534103;5187.87030324243;5256.395514515842;5294.7617357219015;5303.681779093247;5312.203169524583;5295.920561191566;5610.102383618621;5841.123831425299;5884.08796636522;5935.317438494563;5810.680412577307;5916.645001299914;5947.22686050815;5949.167116603679;5964.105384192362;5760.913586296209;5820.708964551798;5821.621594596338;5821.912671309934;5828.912566311508;13220.471181152743;13290.73418531624;13921.30393480332;14143.646408839759;14261.215633140173;2795.3151477887845;3007.1579957611903;3010.3076292452856;3066.9079927601983;3097.7831551791382;10225.539850706662;10264.384136951807;10399.064084232456;10632.734181645443;10685.786284274349;1913.5885784556249;1922.2695375757262;1925.7111433284979;1925.8844469331884;1938.3215874443972;79122.60438697827;82009.58995205046;82745;83834;85234.57382713108;10837.729056773644;11109.666709998713;11115;11943.701407464883;12098.274103553815;15277.6180595486;15329.540113796604;15331.693366132615;15357.385704571692;15389.460424102519;7912.4856884302335;8261.710840120688;8264.710735124361;8355.779014784115;8436.36727245459;7829.530228186327;7921.603919804102;8099.392545559107;8115.391345648982;8155;4578.40480737502;4608.608268297232;4627.259638457898;4647.117047760915;4764.76176191196;1986.3147214211074;2014.174188582674;2021.110711287029;2044.1925439451632;2068.2657656532015;956.5456408206046;965.9130678238852;966.1015255812022;980.612658000086;980.7695191629962;17061.57346066358;18221.18004689792;18369.908150459163;18757.155927983273;18787.530311742317;940.7695114696943;952.3476418653245;955.4362925873768;962.1484985787629;968.1431932739576;9746.463944482979;10126.54430550627;10224.233182511342;10342.948285258675;10432.217583681255;302282;306256;306366;451507;454099;83379.58310208531;90345;91398;92052.53973730089;92475.53762231146;1040.5109598488698;1064.2709743825503;1083.869935607732;1084.5715942202628;1085.8642669666292;16300.510984670478;16628.50114496567;16923.661526769643;16931.830681693027;17229.31082756676;37985.43021854619;38909.6109038906;39633;39702.2059558813;40139.79930100331;50154.24768628547;52381.7380913093;52843.20735189052;53120;54334.72832635811;8380.706675266336;8383.874241886255;8573.18554737299;8573.914260857311;8627.741167764978;11370.715732106764;12761.425735842095;12856.292903890377;13189.142705724098;13197.076204665582;10681.252312338062;10799.56801727922;11116.333020018825;11136;11251.268667536584;10962.616308429167;11115.22193446451;11300.49147788368;11349.829752553553;11963.940180299043;11606.825897611374;11622;11718.70703232426;11780.823287650863;11881.82177267359;8638.654453822028;8653.480791152298;8724.912750872663;8754.69358572447;8909.955450222707;10537.89462105369;11296.152788541056;11321.773564528829;11363.37501437429;11687.357195353998 14:41:32 INFO - PROCESS | 22286 | |3;mootools.html;1380.8342998840135;1381.2886363522787;1383.6402535340808;1384.7161331926957;1402.8947828912828;7776.727814526493;8014.198580141983;8420.32637389009;8868.46789192649;8880.555972201388;8364.205400486959;8623.91376086239;8634.309255259579;8826.60280287387;8846.734597962071;2354.8704821234824;2393.162393162388;2393.4734358441115;2395.8682272475007;2398.340456374502;2299.4251437140715;2326.674265602817;2339.532093581282;2342.754010828864;2399.0523743121457;2388.5103553771523;2402.327348342467;2425.4785221177444;2431.0640403444713;2442.9389265268333;2286.405534561026;2290.4273931517123;2307.826912981525;2315.490592069759;2342.0982921575232;2383.690120284352;2386.868722220277;2401.6277476991095;2404.987975060131;2407.205622144688;8794.296456283482;8938.10618938101;8938.821223575556;8958.328125390623;8961.775955601097;5410.188471729232;5486.094794358926;5525.778968841241;5537.224788529609;5551.583631227674;3567.1807378237027;3598.7840729556046;3682.1899182179877;3698.1863989922176;3755.549334079928;1308.7775078236723;1375.848656647768;1376.4769387632637;1423.2030063164661;1434.2398528779659;11400.71498212543;11422.714432139262;11469.483873225725;11496.482658280398;11509.366984815832;4451.1765323415275;4634.189016922025;4730.148573256778;4758.928616070762;4791.137595232892;9458.810823783486;9494.287928405398;9507.144357007903;9510.524473776284;9521.809563808685;5237.476252374732;5347.41178470368;5356.330458692663;5362.517373436332;5383.40782513931;4933;4937.259411088366;4958.950410495849;4994.675346102492;5034.823781167642;3680.429533422324;3769.754965927206;3828.2917660232947;3863.4977452931503;3952.2885880541203;1110.6723516562633;1164.2839653613137;1175.2478413815165;1176.3059794721155;1217.3426349771025;864.6843901975734;869.799946012408;875.8642410426394;882.761654353321;900.4642237868458;5909.349971503131;6012.969935150384;6022.45797878193;6040.788572399945;6154.046122850965;938.8685584018243;955.2167222877174;955.6607404371487;961.5672947173799;983.7491439682835;3808.7143464240294;4112.691548133902;4158.126793373365;4162.895927601834;4177.227212965611;10531.894681053245;10776.29954052984;10828;10837.458127093614;10847.349159050482;10610;10754.73113172177;10788.136949044057;10818.242723009314;10825.458727063615;7948.761537153779;8156.184381561797;8311.875321870177;8316.251537361666;8316.875246871301;5553.278073850373;5640.605157638925;5661.971690141523;5675;5791.420857914175;9408;9446.622135114518;9516.095970882756;9539.47532885698;9609;9787.804243915225;9802.950985245028;9898.10917017472;9938.701838944844;9958;7133.82165445868;7195.064641596559;7234.095738032746;7297.306755858185;7377.372923301579;8135.552544609866;8185.181481851767;8187.549684767395;8191.959040204761;8227.588620569068;7472.422253695353;7600.791145562889;7743.7676869694;7886.605669716377;7922.445428819928;7351.632418378953;7487.3261406473675;7528.736494222676;7564.773056808304;7583.7724868254045;6443.452306553995;6567.507436942153;6571.967140164268;6579.2104947404705;6587.176602924635;4988.376452943382;5064.46823083571;5091.618128640441;5103.821366252163;5337.439568845215;5079.1873300273255;5156.2523434102595;5166.2508936203285;5349.438308977501;5355.1431770918025 14:41:32 INFO - PROCESS | 22286 | |4;prototype.html;378.20388083085106;396.6033966033966;397.1718965955982;397.4812869205686;405.691674327511;5361.356637203541;5379.870227252277;5395.433479484656;5416.891662166754;5440.619156659035;5324.307839980806;5369.5972802039905;5396.514313711765;5396.704790850197;5397.595180361479;4233.767142807144;4261.211675839964;4274.465691788527;4287.035417031158;4295.312749960007;4284.635805956497;4305.526392096866;4305.978470107645;4307.483102027761;4307.956920430803;4270.423492828475;4277.871663850074;4283.657307415399;4286.807093680792;4323.718958267718;4137.689673274486;4266.530681625019;4289.227938970984;4302.548232435596;4305.311150215982;3860.6911447084167;3870.612938706135;3870.748401353903;3872.593377695358;3880.1269714314053;5260.263563101169;5265.894682106336;5277.419483856773;5278.703763862732;5286.550643195333;4593.127305811919;4816.494268101834;4829.686070405448;4980.850574482772;4986.5761410280165;5676.545876329884;5676.5524791178395;5684.744186511617;5689.231953686241;5689.743961521742;4042.555318914917;4063.5142557547074;4087.8569250076102;4088.3867419886956;4104.712670113093;5320.308359913225;5746.827595172152;5849.824505264849;5911;5914.2015827863115;4109.773962432095;4147.797138829766;4150.543440221573;4159.396887451303;4175.64507016907;4157.272477316457;4185.162967406531;4194.902548725637;4199.874003779892;4201.62185403309;4561.7262964222255;4772.665913386029;4821.662483626183;4829.348038014823;4833.564979151894;5633.211350410946;5647.435256474402;5654.660720356792;5672.475748292053;5675.716214189274;1393.9163650181022;1406.6566429060224;1407.2893188939724;1412.2021058102139;1430.5708287513705;911.3245476881867;948.7201275623707;953.7186529973675;958.2046901072093;959.4291396619001;5223.649357788934;5226;5293.788248470018;5343.412224655285;5368.194770784414;5629.887402252014;5644.209810626434;5650.5479561634975;5667.206591077253;5712.028955077647;5016.272640467112;5219.582433405394;5246.081935661244;5296.814611488657;5308.381707914139;2990.9551356729667;2991.282092297833;3001.729844314022;3004.489236829744;3007.2331555453657;5794.391588883191;5833;5867.765289388462;5872.559558033079;5877.001819381346;5573.247611572386;5816.651000939957;5964.284285885721;5976.760929562769;6037.45662890342;6161.907571386522;6168.691565421801;6175.2898416681755;6195.721192546436;6196.318404975449;5576.525995290446;5885.646861188342;5887.882242355215;5901.668840807943;5927.229460170151;5680.77276908919;5746.563848489551;5759.191075452044;5776.277965254343;5798.5651076168615;6100.23747031621;6101.420365065312;6125.938740612537;6162.630242185483;6183.237777233467;4109.2808758467745;4145.378193270913;4165.91668166641;4183.309753890574;4186.539480657186;5860.882782344414;5879.911801322898;5881.441263080001;5893.5874488785375;5899.557533185114;5702.515286200553;5709.857253568694;5713.54304652311;5741.91387129185;5764.99112655292;5513.476219759116;5635.746391412315;5647.644565304379;5655.745491452977;5676.77292908279;5607.5513958884;5660.235868157911;5680.573956953328;5691.516221121251;5731.828045158652;5449.264349312951;5652.519535839499;5722.284714410699;5751.338596061507;5756.971215144065;5499.532539734168;5522.751476183501;5553.4724201200825;5556.444355564411;5568.248286481273 14:41:32 INFO - PROCESS | 22286 | |5;yui.html;696.2654399608414;745.4818900863899;781.4100354232554;798.3294033012269;801.138775815998;15817.920910395445;16387.918060409713;16448.177591120442;16520.917395413035;16570.83429165708;15264.778817694585;15533.301001454933;15536.61158471036;15550.53348399547;15564.53306400807;692.4322055914153;716.6595866963203;803.2329125684986;804.6419343392192;819.3813670678629;820.5774026376421;826.6197549127409;826.9875951860727;827.4001349021971;828.5981299069977;887.3744010472634;887.7514295997131;888.2183678363064;889.9911000889973;890.875277461156;877.368294827723;884.7080463447079;884.9557522123868;885.2254277507182;886.264400547544;647.7117682631231;648.1282674802384;651.0592194279268;652.9510286728514;653.1802587752388;14663.266836658231;14765.261736913219;14773.630659233606;14909.254537273202;15353.462628808049;14432.422703091865;14706.852931470656;15204.08775473464;15223.467178648694;15223.619409514851;12038.879611203864;12052.45763940616;12118.768109448307;12163.452644631012;12415.31715755633;3774.8867533974026;3795.335816232176;3810.618938106195;3832.559255685604;3945.5265368156006;83548;85941.57029214877;86716.03853202739;86862.13137868667;87344;10376.42929638862;10483.266171368083;10754.085902698202;10768.56925722978;11196;21308;21480.24819131323;21585.67621485679;21730.456738581663;21816.563668726852;7813.648385822652;7899.170587088388;7953.960230198812;7968.721094761656;8317.918670572908;4377.693561450732;4467.620252278528;4485.147821913826;4497.977510112429;4566.452025756864;3592.9640703593154;3658.396364599811;3668.5047518585184;3713.4986776785327;3727.7390582658954;617.3641149615903;619.7861737700487;623.7598524567983;629.1820633176852;644.1175589442494;715.9713611455587;719.363363423374;740.6000759589762;744.9702011919571;760.9733659321897;8601.698940537051;8764.255038321815;8776;8889;9089.68240085954;682.1950098883365;682.4847240333517;705.6894966214849;705.9258777828356;710.5843081797103;4026.859059932888;4203.936940945889;4260.169266992969;4300.204462174508;4317.771241732494;79612;81898;82608.86955652313;82825;84000;58102.709486452295;59963.400365996655;69385.30614693889;73722;73768.63115684387;52414;52615.73692131515;59072;59323;60021.69989150026;9224.169824715807;9580.13778759915;9721.951390243003;9745.707628771148;9787.85318220213;41439.7928010358;42444;42951.35572966345;43629.78185109054;45813.541864580926;50912.745436272584;51603.74198128985;52182.47817521776;52375.47624523706;54314;12637.304948227937;12789.488420463449;12843.871561284268;13066.80399794023;13103.14829536077;12965.546205882749;13101.344932753134;13712.108712933627;13906.860931390556;14144.434222631391;13394.464221431033;14033.023571172405;14370.13779173253;14535.927320363331;14763.261836908327;13039.543615973396;13192.340382981005;13327.200367977564;13636;13676.111052781538;11835.408229588314;13951.651208719457;14210.502632407815;14273.14361138335;14448.927755361156;10494.370337779757;10689.839652405064;10841.295315804447;10995.175361847734;11179.385133817717;10193.898061019296;11594.623102263848;12247.265164090182;12302.384880756106;12710.555130570385 14:41:32 INFO - PROCESS | 22286 | __end_tp_report 14:41:32 INFO - PROCESS | 22286 | __start_cc_report 14:41:32 INFO - PROCESS | 22286 | _x_x_mozilla_cycle_collect,322 14:41:32 INFO - PROCESS | 22286 | __end_cc_report 14:41:32 INFO - PROCESS | 22286 | __startTimestamp1483051292896__endTimestamp 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | ------- Summary: start ------- 14:41:32 INFO - PROCESS | 22286 | Number of tests: 6 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | [#0] dojo.html Cycles:165 Average:5294.81 Median:5497.56 stddev:606.95 (11.0%) stddev-sans-first:583.33 14:41:32 INFO - PROCESS | 22286 | Values: 3076.6 3145.8 3151.7 3154.9 3191.3 5193.8 5196.7 5244.1 5302.8 5326.7 5263.6 5292.7 5359.5 5373.4 5423.3 4754.6 4820.5 4841.8 4927.5 4950.2 4811.0 4844.0 4891.2 4894.0 4897.7 4758.9 4887.3 4899.4 4919.4 4992.1 4797.3 4811.0 4855.2 4873.4 4927.7 4811.1 4816.6 4838.1 4854.6 4888.4 5323.7 5337.2 5342.9 5353.1 5372.6 5513.2 5538.7 5600.2 5644.0 5661.5 5715.3 5721.8 5745.0 5760.1 5800.2 5334.6 5335.3 5354.1 5354.8 5361.5 5687.6 5730.9 5765.6 5812.8 5896.2 5589.8 5607.4 5608.9 5668.4 5672.9 5688.0 5692.9 5732.0 5741.2 5756.9 5581.0 5624.3 5653.3 5695.4 5701.9 5687.6 5750.3 5753.5 5765.1 5798.0 4304.1 4329.6 4330.3 4334.5 4365.7 3763.9 3771.8 3792.6 3819.4 3894.6 5461.4 5461.5 5464.4 5520.5 5536.8 5713.9 5731.1 5749.9 5750.6 5771.0 5312.4 5339.4 5346.2 5469.3 5478.9 4714.9 4746.6 4747.3 4779.2 4787.0 5974.2 5983.9 5986.4 5990.7 6009.4 5569.2 5582.6 5667.7 5758.1 5777.3 5905.6 5914.4 5914.5 5918.4 5919.9 5530.2 5573.9 5603.3 5606.6 5700.7 5746.9 5747.5 5752.5 5755.0 5758.2 5590.3 5596.5 5604.4 5605.3 5608.7 5394.8 5455.0 5455.2 5536.4 5552.4 5451.3 5576.4 5582.5 5587.6 5607.7 5497.6 5587.6 5588.9 5605.4 5632.1 5421.6 5424.9 5443.5 5455.1 5455.7 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | [#1] ext.html Cycles:180 Average:54517.25 Median:34621.33 stddev:105759.75 (305.5%) stddev-sans-first:105978.77 14:41:32 INFO - PROCESS | 22286 | Values: 535.9 571.7 578.3 611.7 635.9 38280.8 42058.0 42101.8 42265.5 42301.2 42503.8 43310.4 43312.8 43316.8 43381.6 8567.7 8828.8 9952.2 10315.3 10361.3 10164.3 10195.3 10224.0 10232.9 10264.3 10258.8 10262.8 10270.9 10327.5 10344.5 10220.2 10226.6 10272.2 10299.6 10309.2 10049.6 10079.2 10083.5 10107.5 10112.8 38605.8 38773.0 38872.2 38913.2 39070.4 3804.2 3806.3 3867.6 3915.7 3917.2 29664.3 29868.6 29996.7 30004.7 30794.0 4228.3 4290.7 4368.1 4472.9 4493.9 319891.0 322297.0 322575.4 322844.0 323543.0 51857.0 52022.2 52189.0 52319.7 52350.7 53213.0 53535.7 53604.2 53752.7 53903.7 14228.3 15816.8 16000.9 16002.5 16033.1 10925.7 12179.9 12446.3 12514.7 12688.5 16774.2 16818.8 16823.2 16833.0 16843.0 6747.4 6777.7 6859.7 7081.9 7112.1 901.1 911.3 912.8 917.1 933.4 29880.0 30946.8 30977.5 31155.0 31408.7 1059.5 1073.6 1077.6 1079.4 1090.9 15364.8 15661.5 16299.8 16432.2 16654.7 356222.0 462105.0 464673.0 466594.0 467176.0 53380.5 53612.0 53796.7 54039.5 54167.5 1355.6 1372.3 1395.3 1413.4 1415.0 27880.9 28763.3 29536.4 29541.1 29751.6 45389.5 46268.8 46282.8 46326.5 46341.1 418999.0 420553.0 420912.0 422490.0 424481.0 22722.3 23082.8 24270.9 25161.0 25215.2 40181.4 40387.6 40549.0 40615.4 41036.6 35282.6 35459.6 35652.3 35781.6 36046.0 34314.0 34487.8 34528.5 34792.0 35054.5 34615.7 34627.0 35046.0 35387.5 35828.8 30834.5 31730.2 31944.8 32185.7 32234.4 23805.4 24264.2 24456.1 24634.9 24686.0 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | [#2] jquery.html Cycles:180 Average:24766.23 Median:3082.35 stddev:62097.54 (2014.6%) stddev-sans-first:62244.80 14:41:32 INFO - PROCESS | 22286 | Values: 402.3 410.8 411.2 413.5 425.4 15866.4 16450.5 18103.9 18768.5 18865.4 12099.8 12829.1 12840.0 17206.3 19409.8 5113.8 5146.4 5304.8 5327.9 5340.9 5187.9 5256.4 5294.8 5303.7 5312.2 5295.9 5610.1 5841.1 5884.1 5935.3 5810.7 5916.6 5947.2 5949.2 5964.1 5760.9 5820.7 5821.6 5821.9 5828.9 13220.5 13290.7 13921.3 14143.6 14261.2 2795.3 3007.2 3010.3 3066.9 3097.8 10225.5 10264.4 10399.1 10632.7 10685.8 1913.6 1922.3 1925.7 1925.9 1938.3 79122.6 82009.6 82745.0 83834.0 85234.6 10837.7 11109.7 11115.0 11943.7 12098.3 15277.6 15329.5 15331.7 15357.4 15389.5 7912.5 8261.7 8264.7 8355.8 8436.4 7829.5 7921.6 8099.4 8115.4 8155.0 4578.4 4608.6 4627.3 4647.1 4764.8 1986.3 2014.2 2021.1 2044.2 2068.3 956.5 965.9 966.1 980.6 980.8 17061.6 18221.2 18369.9 18757.2 18787.5 940.8 952.3 955.4 962.1 968.1 9746.5 10126.5 10224.2 10342.9 10432.2 302282.0 306256.0 306366.0 451507.0 454099.0 83379.6 90345.0 91398.0 92052.5 92475.5 1040.5 1064.3 1083.9 1084.6 1085.9 16300.5 16628.5 16923.7 16931.8 17229.3 37985.4 38909.6 39633.0 39702.2 40139.8 50154.2 52381.7 52843.2 53120.0 54334.7 8380.7 8383.9 8573.2 8573.9 8627.7 11370.7 12761.4 12856.3 13189.1 13197.1 10681.3 10799.6 11116.3 11136.0 11251.3 10962.6 11115.2 11300.5 11349.8 11963.9 11606.8 11622.0 11718.7 11780.8 11881.8 8638.7 8653.5 8724.9 8754.7 8910.0 10537.9 11296.2 11321.8 11363.4 11687.4 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | [#3] mootools.html Cycles:180 Average:5693.36 Median:5352.29 stddev:3136.43 (58.6%) stddev-sans-first:3128.49 14:41:32 INFO - PROCESS | 22286 | Values: 1380.8 1381.3 1383.6 1384.7 1402.9 7776.7 8014.2 8420.3 8868.5 8880.6 8364.2 8623.9 8634.3 8826.6 8846.7 2354.9 2393.2 2393.5 2395.9 2398.3 2299.4 2326.7 2339.5 2342.8 2399.1 2388.5 2402.3 2425.5 2431.1 2442.9 2286.4 2290.4 2307.8 2315.5 2342.1 2383.7 2386.9 2401.6 2405.0 2407.2 8794.3 8938.1 8938.8 8958.3 8961.8 5410.2 5486.1 5525.8 5537.2 5551.6 3567.2 3598.8 3682.2 3698.2 3755.5 1308.8 1375.8 1376.5 1423.2 1434.2 11400.7 11422.7 11469.5 11496.5 11509.4 4451.2 4634.2 4730.1 4758.9 4791.1 9458.8 9494.3 9507.1 9510.5 9521.8 5237.5 5347.4 5356.3 5362.5 5383.4 4933.0 4937.3 4959.0 4994.7 5034.8 3680.4 3769.8 3828.3 3863.5 3952.3 1110.7 1164.3 1175.2 1176.3 1217.3 864.7 869.8 875.9 882.8 900.5 5909.3 6013.0 6022.5 6040.8 6154.0 938.9 955.2 955.7 961.6 983.7 3808.7 4112.7 4158.1 4162.9 4177.2 10531.9 10776.3 10828.0 10837.5 10847.3 10610.0 10754.7 10788.1 10818.2 10825.5 7948.8 8156.2 8311.9 8316.3 8316.9 5553.3 5640.6 5662.0 5675.0 5791.4 9408.0 9446.6 9516.1 9539.5 9609.0 9787.8 9803.0 9898.1 9938.7 9958.0 7133.8 7195.1 7234.1 7297.3 7377.4 8135.6 8185.2 8187.5 8192.0 8227.6 7472.4 7600.8 7743.8 7886.6 7922.4 7351.6 7487.3 7528.7 7564.8 7583.8 6443.5 6567.5 6572.0 6579.2 6587.2 4988.4 5064.5 5091.6 5103.8 5337.4 5079.2 5156.3 5166.3 5349.4 5355.1 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | [#4] prototype.html Cycles:180 Average:4776.91 Median:5395.97 stddev:1398.37 (25.9%) stddev-sans-first:1362.76 14:41:32 INFO - PROCESS | 22286 | Values: 378.2 396.6 397.2 397.5 405.7 5361.4 5379.9 5395.4 5416.9 5440.6 5324.3 5369.6 5396.5 5396.7 5397.6 4233.8 4261.2 4274.5 4287.0 4295.3 4284.6 4305.5 4306.0 4307.5 4308.0 4270.4 4277.9 4283.7 4286.8 4323.7 4137.7 4266.5 4289.2 4302.5 4305.3 3860.7 3870.6 3870.7 3872.6 3880.1 5260.3 5265.9 5277.4 5278.7 5286.6 4593.1 4816.5 4829.7 4980.9 4986.6 5676.5 5676.6 5684.7 5689.2 5689.7 4042.6 4063.5 4087.9 4088.4 4104.7 5320.3 5746.8 5849.8 5911.0 5914.2 4109.8 4147.8 4150.5 4159.4 4175.6 4157.3 4185.2 4194.9 4199.9 4201.6 4561.7 4772.7 4821.7 4829.3 4833.6 5633.2 5647.4 5654.7 5672.5 5675.7 1393.9 1406.7 1407.3 1412.2 1430.6 911.3 948.7 953.7 958.2 959.4 5223.6 5226.0 5293.8 5343.4 5368.2 5629.9 5644.2 5650.5 5667.2 5712.0 5016.3 5219.6 5246.1 5296.8 5308.4 2991.0 2991.3 3001.7 3004.5 3007.2 5794.4 5833.0 5867.8 5872.6 5877.0 5573.2 5816.7 5964.3 5976.8 6037.5 6161.9 6168.7 6175.3 6195.7 6196.3 5576.5 5885.6 5887.9 5901.7 5927.2 5680.8 5746.6 5759.2 5776.3 5798.6 6100.2 6101.4 6125.9 6162.6 6183.2 4109.3 4145.4 4165.9 4183.3 4186.5 5860.9 5879.9 5881.4 5893.6 5899.6 5702.5 5709.9 5713.5 5741.9 5765.0 5513.5 5635.7 5647.6 5655.7 5676.8 5607.6 5660.2 5680.6 5691.5 5731.8 5449.3 5652.5 5722.3 5751.3 5757.0 5499.5 5522.8 5553.5 5556.4 5568.2 14:41:32 INFO - PROCESS | 22286 | 14:41:32 INFO - PROCESS | 22286 | [#5] yui.html Cycles:180 Average:17598.46 Median:4476.38 stddev:22764.07 (508.5%) stddev-sans-first:22792.54 14:41:32 INFO - PROCESS | 22286 | Values: 696.3 745.5 781.4 798.3 801.1 15817.9 16387.9 16448.2 16520.9 16570.8 15264.8 15533.3 15536.6 15550.5 15564.5 692.4 716.7 803.2 804.6 819.4 820.6 826.6 827.0 827.4 828.6 887.4 887.8 888.2 890.0 890.9 877.4 884.7 885.0 885.2 886.3 647.7 648.1 651.1 653.0 653.2 14663.3 14765.3 14773.6 14909.3 15353.5 14432.4 14706.9 15204.1 15223.5 15223.6 12038.9 12052.5 12118.8 12163.5 12415.3 3774.9 3795.3 3810.6 3832.6 3945.5 83548.0 85941.6 86716.0 86862.1 87344.0 10376.4 10483.3 10754.1 10768.6 11196.0 21308.0 21480.2 21585.7 21730.5 21816.6 7813.6 7899.2 7954.0 7968.7 8317.9 4377.7 4467.6 4485.1 4498.0 4566.5 3593.0 3658.4 3668.5 3713.5 3727.7 617.4 619.8 623.8 629.2 644.1 716.0 719.4 740.6 745.0 761.0 8601.7 8764.3 8776.0 8889.0 9089.7 682.2 682.5 705.7 705.9 710.6 4026.9 4203.9 4260.2 4300.2 4317.8 79612.0 81898.0 82608.9 82825.0 84000.0 58102.7 59963.4 69385.3 73722.0 73768.6 52414.0 52615.7 59072.0 59323.0 60021.7 9224.2 9580.1 9722.0 9745.7 9787.9 41439.8 42444.0 42951.4 43629.8 45813.5 50912.7 51603.7 52182.5 52375.5 54314.0 12637.3 12789.5 12843.9 13066.8 13103.1 12965.5 13101.3 13712.1 13906.9 14144.4 13394.5 14033.0 14370.1 14535.9 14763.3 13039.5 13192.3 13327.2 13636.0 13676.1 11835.4 13951.7 14210.5 14273.1 14448.9 10494.4 10689.8 10841.3 10995.2 11179.4 10193.9 11594.6 12247.3 12302.4 12710.6 14:41:32 INFO - PROCESS | 22286 | -------- Summary: end -------- 14:41:32 INFO - PROCESS | 22286 | 14:41:33 INFO - TEST-INFO | 22286: exit 0 14:41:33 INFO - TEST-OK | dromaeo_css | took 1114497ms 14:41:33 INFO - TEST-START | kraken 14:41:33 INFO - operating with platform_type : linux_ 14:41:33 INFO - Initialising browser for kraken test... 14:41:33 INFO - TEST-INFO | started process 22632 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT7tL_2/profile http://localhost:51699/getInfo.html) 14:41:34 INFO - PROCESS | 22632 | 1483051294189 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 14:41:37 INFO - PROCESS | 22632 | __metrics Screen width/height:1600/1200 14:41:37 INFO - PROCESS | 22632 | colorDepth:24 14:41:37 INFO - PROCESS | 22632 | Browser inner width/height: 1024/697 14:41:37 INFO - PROCESS | 22632 | __metrics 14:41:38 ERROR - PROCESS | 22632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 14:41:39 INFO - TEST-INFO | 22632: exit 0 14:41:39 INFO - Browser initialized. 14:41:39 INFO - Running cycle 1/1 for kraken test... 14:41:39 INFO - TEST-INFO | started process 22755 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT7tL_2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:41:51 INFO - PROCESS | 22755 | RSS: Main: 154402816 14:41:51 INFO - PROCESS | 22755 | 14:41:53 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51699/tests/kraken/driver.html?testName=audio-beat-detection) 14:41:54 INFO - PROCESS | 22755 | RSS: Main: 160538624 14:41:54 INFO - PROCESS | 22755 | 14:41:57 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51699/tests/kraken/driver.html?testName=audio-dft) 14:41:57 INFO - PROCESS | 22755 | RSS: Main: 159715328 14:41:57 INFO - PROCESS | 22755 | 14:42:00 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51699/tests/kraken/driver.html?testName=audio-fft) 14:42:01 INFO - PROCESS | 22755 | RSS: Main: 156041216 14:42:01 INFO - PROCESS | 22755 | 14:42:03 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51699/tests/kraken/driver.html?testName=audio-oscillator) 14:42:04 INFO - PROCESS | 22755 | RSS: Main: 156794880 14:42:04 INFO - PROCESS | 22755 | 14:42:06 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51699/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:42:06 INFO - PROCESS | 22755 | RSS: Main: 156905472 14:42:06 INFO - PROCESS | 22755 | 14:42:09 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51699/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:10 INFO - PROCESS | 22755 | RSS: Main: 161083392 14:42:10 INFO - PROCESS | 22755 | 14:42:14 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51699/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:14 INFO - PROCESS | 22755 | RSS: Main: 159543296 14:42:14 INFO - PROCESS | 22755 | 14:42:17 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51699/tests/kraken/driver.html?testName=json-parse-financial) 14:42:18 INFO - PROCESS | 22755 | RSS: Main: 159952896 14:42:18 INFO - PROCESS | 22755 | 14:42:19 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51699/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:42:20 INFO - PROCESS | 22755 | RSS: Main: 159821824 14:42:20 INFO - PROCESS | 22755 | 14:42:22 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:42:22 INFO - PROCESS | 22755 | RSS: Main: 159981568 14:42:22 INFO - PROCESS | 22755 | 14:42:24 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:42:25 INFO - PROCESS | 22755 | RSS: Main: 159748096 14:42:25 INFO - PROCESS | 22755 | 14:42:28 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:42:28 INFO - PROCESS | 22755 | RSS: Main: 159973376 14:42:28 INFO - PROCESS | 22755 | 14:42:31 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:42:32 INFO - PROCESS | 22755 | RSS: Main: 160059392 14:42:32 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | Cycle 1(1): loaded http://localhost:51699/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51699/tests/kraken/driver.html?testName=ai-astar) 14:42:34 INFO - PROCESS | 22755 | RSS: Main: 162099200 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | __start_tp_report 14:42:34 INFO - PROCESS | 22755 | _x_x_mozilla_page_load 14:42:34 INFO - PROCESS | 22755 | _x_x_mozilla_page_load_details 14:42:34 INFO - PROCESS | 22755 | |i|pagename|runs| 14:42:34 INFO - PROCESS | 22755 | |0;ai-astar;102;111;110;111;111;112;112;112;110;112 14:42:34 INFO - PROCESS | 22755 | |1;audio-beat-detection;154;123;124;172;123;121;121;122;142;126 14:42:34 INFO - PROCESS | 22755 | |2;audio-dft;163;165;165;165;162;165;166;163;162;163 14:42:34 INFO - PROCESS | 22755 | |3;audio-fft;78;78;77;100;79;76;76;76;105;75 14:42:34 INFO - PROCESS | 22755 | |4;audio-oscillator;84;85;108;84;83;83;105;84;82;105 14:42:34 INFO - PROCESS | 22755 | |5;imaging-gaussian-blur;118;118;120;118;118;118;118;118;118;122 14:42:34 INFO - PROCESS | 22755 | |6;imaging-darkroom;148;147;147;147;148;147;148;149;147;146 14:42:34 INFO - PROCESS | 22755 | |7;imaging-desaturate;96;96;96;96;96;96;97;98;97;96 14:42:34 INFO - PROCESS | 22755 | |8;json-parse-financial;56;57;62;60;58;58;58;58;57;58 14:42:34 INFO - PROCESS | 22755 | |9;json-stringify-tinderbox;68;68;71;68;68;67;68;67;68;68 14:42:34 INFO - PROCESS | 22755 | |10;stanford-crypto-aes;80;82;82;81;81;82;87;82;80;81 14:42:34 INFO - PROCESS | 22755 | |11;stanford-crypto-ccm;128;134;126;126;128;146;127;133;168;131 14:42:34 INFO - PROCESS | 22755 | |12;stanford-crypto-pbkdf2;170;165;172;168;166;166;167;171;169;166 14:42:34 INFO - PROCESS | 22755 | |13;stanford-crypto-sha256-iterative;64;64;67;63;63;62;67;63;62;62 14:42:34 INFO - PROCESS | 22755 | __end_tp_report 14:42:34 INFO - PROCESS | 22755 | __start_cc_report 14:42:34 INFO - PROCESS | 22755 | _x_x_mozilla_cycle_collect,720 14:42:34 INFO - PROCESS | 22755 | __end_cc_report 14:42:34 INFO - PROCESS | 22755 | __startTimestamp1483051354275__endTimestamp 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | ------- Summary: start ------- 14:42:34 INFO - PROCESS | 22755 | Number of tests: 14 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.50 stddev:3.02 (2.7%) stddev-sans-first:0.83 14:42:34 INFO - PROCESS | 22755 | Values: 102.0 111.0 110.0 111.0 111.0 112.0 112.0 112.0 110.0 112.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:125.00 stddev:17.58 (14.1%) stddev-sans-first:16.89 14:42:34 INFO - PROCESS | 22755 | Values: 154.0 123.0 124.0 172.0 123.0 121.0 121.0 122.0 142.0 126.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#2] audio-dft Cycles:10 Average:163.90 Median:165.00 stddev:1.45 (0.9%) stddev-sans-first:1.50 14:42:34 INFO - PROCESS | 22755 | Values: 163.0 165.0 165.0 165.0 162.0 165.0 166.0 163.0 162.0 163.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.50 stddev:10.93 (14.3%) stddev-sans-first:11.50 14:42:34 INFO - PROCESS | 22755 | Values: 78.0 78.0 77.0 100.0 79.0 76.0 76.0 76.0 105.0 75.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:83.50 stddev:10.89 (13.0%) stddev-sans-first:11.31 14:42:34 INFO - PROCESS | 22755 | Values: 84.0 85.0 108.0 84.0 83.0 83.0 105.0 84.0 82.0 105.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#5] imaging-gaussian-blur Cycles:10 Average:118.60 Median:118.00 stddev:1.35 (1.1%) stddev-sans-first:1.41 14:42:34 INFO - PROCESS | 22755 | Values: 118.0 118.0 120.0 118.0 118.0 118.0 118.0 118.0 118.0 122.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 14:42:34 INFO - PROCESS | 22755 | Values: 148.0 147.0 147.0 147.0 148.0 147.0 148.0 149.0 147.0 146.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 14:42:34 INFO - PROCESS | 22755 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 97.0 98.0 97.0 96.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.00 stddev:1.69 (2.9%) stddev-sans-first:1.59 14:42:34 INFO - PROCESS | 22755 | Values: 56.0 57.0 62.0 60.0 58.0 58.0 58.0 58.0 57.0 58.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 14:42:34 INFO - PROCESS | 22755 | Values: 68.0 68.0 71.0 68.0 68.0 67.0 68.0 67.0 68.0 68.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.99 (2.4%) stddev-sans-first:2.00 14:42:34 INFO - PROCESS | 22755 | Values: 80.0 82.0 82.0 81.0 81.0 82.0 87.0 82.0 80.0 81.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:132.00 stddev:13.14 (10.0%) stddev-sans-first:13.71 14:42:34 INFO - PROCESS | 22755 | Values: 128.0 134.0 126.0 126.0 128.0 146.0 127.0 133.0 168.0 131.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.50 stddev:2.40 (1.4%) stddev-sans-first:2.44 14:42:34 INFO - PROCESS | 22755 | Values: 170.0 165.0 172.0 168.0 166.0 166.0 167.0 171.0 169.0 166.0 14:42:34 INFO - PROCESS | 22755 | 14:42:34 INFO - PROCESS | 22755 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:63.50 stddev:1.89 (3.0%) stddev-sans-first:2.00 14:42:34 INFO - PROCESS | 22755 | Values: 64.0 64.0 67.0 63.0 63.0 62.0 67.0 63.0 62.0 62.0 14:42:34 INFO - PROCESS | 22755 | -------- Summary: end -------- 14:42:34 INFO - PROCESS | 22755 | 14:42:35 INFO - TEST-INFO | 22755: exit 0 14:42:35 INFO - TEST-OK | kraken | took 61562ms 14:42:35 INFO - SUITE-END | took 1176s 14:42:35 INFO - Completed test suite (00:19:36) 14:42:35 INFO - javascript benchmark 14:42:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7104.752398581346, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.615423072116, 3145.8112513249207, 3151.653318135006, 3154.9053528394147, 3191.3298207376465, 5193.818216362428, 5196.688198708081, 5244.05606990742, 5302.78788848446, 5326.7070311132875, 5263.631545791796, 5292.655977361469, 5359.5444387227135, 5373.408925018254, 5423.267858839065, 4754.619630429566, 4820.493848145929, 4841.782119804618, 4927.457979622239, 4950.207966725325, 4811.0, 4844.031193761244, 4891.241857512091, 4894.0, 4897.657163998521, 4758.857234282977, 4887.315775791392, 4899.436564795023, 4919.360483137187, 4992.051510213048, 4797.328374027639, 4811.0, 4855.247436647325, 4873.439554451248, 4927.6550641455115, 4811.085893680224, 4816.56650901417, 4838.056578967103, 4854.611631069506, 4888.41339039314, 5323.680579165224, 5337.226102215196, 5342.893142137135, 5353.116735738598, 5372.623916325859, 5513.20058591506, 5538.695371754552, 5600.1599760035915, 5644.040513112781, 5661.518770904437, 5715.2855893013375, 5721.7711291548285, 5745.0, 5760.107183386582, 5800.187973683687, 5334.59990500714, 5335.279737235501, 5354.063038968165, 5354.785808567653, 5361.463853614647, 5687.601867869293, 5730.942690573124, 5765.625234359753, 5812.82561523155, 5896.204012458349, 5589.776408943678, 5607.4112218217315, 5608.887822243532, 5668.404817494186, 5672.858178545487, 5687.971560142173, 5692.857678557986, 5732.0255556555485, 5741.224934633771, 5756.884862302731, 5580.972095139579, 5624.325080990226, 5653.321601407857, 5695.430456954271, 5701.885962280731, 5687.601867869293, 5750.30996280449, 5753.51095156908, 5765.106408506688, 5798.043322851682, 4304.074623955865, 4329.567043295645, 4330.285502891988, 4334.523202447728, 4365.672574556921, 3763.8870833875026, 3771.773693578394, 3792.6207379262405, 3819.350710379242, 3894.6105389460827, 5461.42655021225, 5461.453854614586, 5464.426235245322, 5520.503154716095, 5536.806211782568, 5713.885722285531, 5731.0543760279925, 5749.9425005750245, 5750.568707346965, 5771.047777116729, 5312.442193569696, 5339.439358867263, 5346.198070289488, 5469.288992430958, 5478.890422191614, 4714.858554243378, 4746.59653929413, 4747.335373047776, 4779.235322348408, 4787.0, 5974.2233509643465, 5983.910241346383, 5986.431289027535, 5990.73041713124, 6009.429104235091, 5569.19246709233, 5582.637128586629, 5667.716614169358, 5758.107493338539, 5777.3356064051495, 5905.616134951214, 5914.4381283777975, 5914.497267732291, 5918.437748413894, 5919.911201331897, 5530.225768392348, 5573.8606534835335, 5603.327600687943, 5606.635568688022, 5700.743466543932, 5746.856328591819, 5747.453991870766, 5752.482276595127, 5755.021646320052, 5758.165066065313, 5590.273264475755, 5596.468335508121, 5604.355499117653, 5605.271314729059, 5608.663480191201, 5394.811181608625, 5454.972725136349, 5455.236266922555, 5536.446355364431, 5552.416996215501, 5451.318585176853, 5576.386597474356, 5582.469665381782, 5587.552995760248, 5607.74765135578, 5497.560195184456, 5587.55299576041, 5588.916166257591, 5605.439456054362, 5632.070708333079, 5421.647592906448, 5424.891502169856, 5443.455654434525, 5455.1271796512365, 5455.699936503372], "value": 5253.764460728742, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.9276497672814, 571.7484306904962, 578.2771535580524, 611.6696983628838, 635.9300476947537, 38280.808595957016, 42058.0, 42101.78949105254, 42265.505484287074, 42301.15397692045, 42503.78748106263, 43310.35034474477, 43312.78343608286, 43316.78341608295, 43381.566184338146, 8567.70013049545, 8828.779280517974, 9952.154066904322, 10315.277930544828, 10361.274710770249, 10164.339317944346, 10195.286329956905, 10224.028717271884, 10232.89767102327, 10264.281500294945, 10258.846117308247, 10262.794744105113, 10270.948645256763, 10327.483625818679, 10344.534495947662, 10220.23348248877, 10226.590936362574, 10272.229582781247, 10299.588016479294, 10309.226807989431, 10049.598016079348, 10079.244056695778, 10083.546240419199, 10107.49462526878, 10112.848307275397, 38605.806970964964, 38773.0, 38872.22255554873, 38913.221735565414, 39070.41394379087, 3804.220134872372, 3806.2577797329254, 3867.59390264021, 3915.6671682906704, 3917.1578110706337, 29664.25839354012, 29868.55197172044, 29996.700032999608, 30004.69995300041, 30794.0, 4228.323468245097, 4290.656747460227, 4368.060866913629, 4472.8881777955485, 4493.9101217975785, 319891.0, 322297.0, 322575.3553412422, 322844.0, 323543.0, 51857.0, 52022.21966670503, 52189.0, 52319.69200770011, 52350.73824630929, 53213.0, 53535.732321338146, 53604.19593706098, 53752.73123634435, 53903.730481347346, 14228.28858557068, 15816.76274855878, 16000.919995399949, 16002.519924402053, 16033.11817850006, 10925.726856828484, 12179.939100304442, 12446.25322480654, 12514.687132821753, 12688.49246030167, 16774.161291935354, 16818.831811681728, 16823.224168012384, 16833.0, 16843.0, 6747.426468750113, 6777.728890844311, 6859.691313890887, 7081.89377159343, 7112.146542414944, 901.1484147480699, 911.3165126155384, 912.7672443526991, 917.1424717888796, 933.3699752666922, 29880.0, 30946.845265773976, 30977.535336969962, 31155.0, 31408.685913141035, 1059.4543809937888, 1073.5652060915343, 1077.6174458067273, 1079.3523885668535, 1090.8854570270166, 15364.769528457082, 15661.451849185223, 16299.755503667455, 16432.178391080397, 16654.666906661798, 356222.0, 462105.0, 464673.0, 466594.0, 467176.0, 53380.46619533755, 53612.0, 53796.73101634467, 54039.45960540423, 54167.45832541703, 1355.5526676197028, 1372.2521225931891, 1395.3441882315499, 1413.4452102687167, 1414.957551273443, 27880.860595696893, 28763.280917976383, 29536.409271814875, 29541.113766587037, 29751.553726693684, 45389.54610453853, 46268.768656156506, 46282.76858615685, 46326.536734632224, 46341.07317853557, 418999.0, 420553.0, 420912.0, 422490.0, 424481.0, 22722.318330450114, 23082.769172308734, 24270.87864560666, 25161.0, 25215.243542693748, 40181.397279040255, 40387.596124038384, 40549.0, 40615.3907691379, 41036.58963410328, 35282.64717352896, 35459.64540354563, 35652.28695426129, 35781.64218357783, 36046.0, 34314.0, 34487.82756086203, 34528.48207276843, 34792.0, 35054.47418288779, 34615.65384346225, 34627.0, 35046.0, 35387.46918796271, 35828.82085589555, 30834.53748193734, 31730.206744831565, 31944.840275798473, 32185.74699771087, 32234.35531289408, 23805.404864878517, 24264.1507547235, 24456.144034958692, 24634.876825615756, 24686.0], "value": 19095.373228231547, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.3421705511488, 410.7679161273878, 411.1714894487608, 413.4645633904094, 425.41293015638416, 15866.444674436398, 16450.506484805446, 18103.913765174082, 18768.530786730338, 18865.434036978895, 12099.818502722466, 12829.101962862604, 12840.0369972252, 17206.311747530115, 19409.805901940977, 5113.769880355384, 5146.382434107913, 5304.787808487656, 5327.893442131155, 5340.946590534103, 5187.87030324243, 5256.395514515842, 5294.7617357219015, 5303.681779093247, 5312.203169524583, 5295.920561191566, 5610.102383618621, 5841.123831425299, 5884.08796636522, 5935.317438494563, 5810.680412577307, 5916.645001299914, 5947.22686050815, 5949.167116603679, 5964.105384192362, 5760.913586296209, 5820.708964551798, 5821.621594596338, 5821.912671309934, 5828.912566311508, 13220.471181152743, 13290.73418531624, 13921.30393480332, 14143.646408839759, 14261.215633140173, 2795.3151477887845, 3007.1579957611903, 3010.3076292452856, 3066.9079927601983, 3097.7831551791382, 10225.539850706662, 10264.384136951807, 10399.064084232456, 10632.734181645443, 10685.786284274349, 1913.5885784556249, 1922.2695375757262, 1925.7111433284979, 1925.8844469331884, 1938.3215874443972, 79122.60438697827, 82009.58995205046, 82745.0, 83834.0, 85234.57382713108, 10837.729056773644, 11109.666709998713, 11115.0, 11943.701407464883, 12098.274103553815, 15277.6180595486, 15329.540113796604, 15331.693366132615, 15357.385704571692, 15389.460424102519, 7912.4856884302335, 8261.710840120688, 8264.710735124361, 8355.779014784115, 8436.36727245459, 7829.530228186327, 7921.603919804102, 8099.392545559107, 8115.391345648982, 8155.0, 4578.40480737502, 4608.608268297232, 4627.259638457898, 4647.117047760915, 4764.76176191196, 1986.3147214211074, 2014.174188582674, 2021.110711287029, 2044.1925439451632, 2068.2657656532015, 956.5456408206046, 965.9130678238852, 966.1015255812022, 980.612658000086, 980.7695191629962, 17061.57346066358, 18221.18004689792, 18369.908150459163, 18757.155927983273, 18787.530311742317, 940.7695114696943, 952.3476418653245, 955.4362925873768, 962.1484985787629, 968.1431932739576, 9746.463944482979, 10126.54430550627, 10224.233182511342, 10342.948285258675, 10432.217583681255, 302282.0, 306256.0, 306366.0, 451507.0, 454099.0, 83379.58310208531, 90345.0, 91398.0, 92052.53973730089, 92475.53762231146, 1040.5109598488698, 1064.2709743825503, 1083.869935607732, 1084.5715942202628, 1085.8642669666292, 16300.510984670478, 16628.50114496567, 16923.661526769643, 16931.830681693027, 17229.31082756676, 37985.43021854619, 38909.6109038906, 39633.0, 39702.2059558813, 40139.79930100331, 50154.24768628547, 52381.7380913093, 52843.20735189052, 53120.0, 54334.72832635811, 8380.706675266336, 8383.874241886255, 8573.18554737299, 8573.914260857311, 8627.741167764978, 11370.715732106764, 12761.425735842095, 12856.292903890377, 13189.142705724098, 13197.076204665582, 10681.252312338062, 10799.56801727922, 11116.333020018825, 11136.0, 11251.268667536584, 10962.616308429167, 11115.22193446451, 11300.49147788368, 11349.829752553553, 11963.940180299043, 11606.825897611374, 11622.0, 11718.70703232426, 11780.823287650863, 11881.82177267359, 8638.654453822028, 8653.480791152298, 8724.912750872663, 8754.69358572447, 8909.955450222707, 10537.89462105369, 11296.152788541056, 11321.773564528829, 11363.37501437429, 11687.357195353998], "value": 8887.18571511178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.8342998840135, 1381.2886363522787, 1383.6402535340808, 1384.7161331926957, 1402.8947828912828, 7776.727814526493, 8014.198580141983, 8420.32637389009, 8868.46789192649, 8880.555972201388, 8364.205400486959, 8623.91376086239, 8634.309255259579, 8826.60280287387, 8846.734597962071, 2354.8704821234824, 2393.162393162388, 2393.4734358441115, 2395.8682272475007, 2398.340456374502, 2299.4251437140715, 2326.674265602817, 2339.532093581282, 2342.754010828864, 2399.0523743121457, 2388.5103553771523, 2402.327348342467, 2425.4785221177444, 2431.0640403444713, 2442.9389265268333, 2286.405534561026, 2290.4273931517123, 2307.826912981525, 2315.490592069759, 2342.0982921575232, 2383.690120284352, 2386.868722220277, 2401.6277476991095, 2404.987975060131, 2407.205622144688, 8794.296456283482, 8938.10618938101, 8938.821223575556, 8958.328125390623, 8961.775955601097, 5410.188471729232, 5486.094794358926, 5525.778968841241, 5537.224788529609, 5551.583631227674, 3567.1807378237027, 3598.7840729556046, 3682.1899182179877, 3698.1863989922176, 3755.549334079928, 1308.7775078236723, 1375.848656647768, 1376.4769387632637, 1423.2030063164661, 1434.2398528779659, 11400.71498212543, 11422.714432139262, 11469.483873225725, 11496.482658280398, 11509.366984815832, 4451.1765323415275, 4634.189016922025, 4730.148573256778, 4758.928616070762, 4791.137595232892, 9458.810823783486, 9494.287928405398, 9507.144357007903, 9510.524473776284, 9521.809563808685, 5237.476252374732, 5347.41178470368, 5356.330458692663, 5362.517373436332, 5383.40782513931, 4933.0, 4937.259411088366, 4958.950410495849, 4994.675346102492, 5034.823781167642, 3680.429533422324, 3769.754965927206, 3828.2917660232947, 3863.4977452931503, 3952.2885880541203, 1110.6723516562633, 1164.2839653613137, 1175.2478413815165, 1176.3059794721155, 1217.3426349771025, 864.6843901975734, 869.799946012408, 875.8642410426394, 882.761654353321, 900.4642237868458, 5909.349971503131, 6012.969935150384, 6022.45797878193, 6040.788572399945, 6154.046122850965, 938.8685584018243, 955.2167222877174, 955.6607404371487, 961.5672947173799, 983.7491439682835, 3808.7143464240294, 4112.691548133902, 4158.126793373365, 4162.895927601834, 4177.227212965611, 10531.894681053245, 10776.29954052984, 10828.0, 10837.458127093614, 10847.349159050482, 10610.0, 10754.73113172177, 10788.136949044057, 10818.242723009314, 10825.458727063615, 7948.761537153779, 8156.184381561797, 8311.875321870177, 8316.251537361666, 8316.875246871301, 5553.278073850373, 5640.605157638925, 5661.971690141523, 5675.0, 5791.420857914175, 9408.0, 9446.622135114518, 9516.095970882756, 9539.47532885698, 9609.0, 9787.804243915225, 9802.950985245028, 9898.10917017472, 9938.701838944844, 9958.0, 7133.82165445868, 7195.064641596559, 7234.095738032746, 7297.306755858185, 7377.372923301579, 8135.552544609866, 8185.181481851767, 8187.549684767395, 8191.959040204761, 8227.588620569068, 7472.422253695353, 7600.791145562889, 7743.7676869694, 7886.605669716377, 7922.445428819928, 7351.632418378953, 7487.3261406473675, 7528.736494222676, 7564.773056808304, 7583.7724868254045, 6443.452306553995, 6567.507436942153, 6571.967140164268, 6579.2104947404705, 6587.176602924635, 4988.376452943382, 5064.46823083571, 5091.618128640441, 5103.821366252163, 5337.439568845215, 5079.1873300273255, 5156.2523434102595, 5166.2508936203285, 5349.438308977501, 5355.1431770918025], "value": 4598.388561695341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.20388083085106, 396.6033966033966, 397.1718965955982, 397.4812869205686, 405.691674327511, 5361.356637203541, 5379.870227252277, 5395.433479484656, 5416.891662166754, 5440.619156659035, 5324.307839980806, 5369.5972802039905, 5396.514313711765, 5396.704790850197, 5397.595180361479, 4233.767142807144, 4261.211675839964, 4274.465691788527, 4287.035417031158, 4295.312749960007, 4284.635805956497, 4305.526392096866, 4305.978470107645, 4307.483102027761, 4307.956920430803, 4270.423492828475, 4277.871663850074, 4283.657307415399, 4286.807093680792, 4323.718958267718, 4137.689673274486, 4266.530681625019, 4289.227938970984, 4302.548232435596, 4305.311150215982, 3860.6911447084167, 3870.612938706135, 3870.748401353903, 3872.593377695358, 3880.1269714314053, 5260.263563101169, 5265.894682106336, 5277.419483856773, 5278.703763862732, 5286.550643195333, 4593.127305811919, 4816.494268101834, 4829.686070405448, 4980.850574482772, 4986.5761410280165, 5676.545876329884, 5676.5524791178395, 5684.744186511617, 5689.231953686241, 5689.743961521742, 4042.555318914917, 4063.5142557547074, 4087.8569250076102, 4088.3867419886956, 4104.712670113093, 5320.308359913225, 5746.827595172152, 5849.824505264849, 5911.0, 5914.2015827863115, 4109.773962432095, 4147.797138829766, 4150.543440221573, 4159.396887451303, 4175.64507016907, 4157.272477316457, 4185.162967406531, 4194.902548725637, 4199.874003779892, 4201.62185403309, 4561.7262964222255, 4772.665913386029, 4821.662483626183, 4829.348038014823, 4833.564979151894, 5633.211350410946, 5647.435256474402, 5654.660720356792, 5672.475748292053, 5675.716214189274, 1393.9163650181022, 1406.6566429060224, 1407.2893188939724, 1412.2021058102139, 1430.5708287513705, 911.3245476881867, 948.7201275623707, 953.7186529973675, 958.2046901072093, 959.4291396619001, 5223.649357788934, 5226.0, 5293.788248470018, 5343.412224655285, 5368.194770784414, 5629.887402252014, 5644.209810626434, 5650.5479561634975, 5667.206591077253, 5712.028955077647, 5016.272640467112, 5219.582433405394, 5246.081935661244, 5296.814611488657, 5308.381707914139, 2990.9551356729667, 2991.282092297833, 3001.729844314022, 3004.489236829744, 3007.2331555453657, 5794.391588883191, 5833.0, 5867.765289388462, 5872.559558033079, 5877.001819381346, 5573.247611572386, 5816.651000939957, 5964.284285885721, 5976.760929562769, 6037.45662890342, 6161.907571386522, 6168.691565421801, 6175.2898416681755, 6195.721192546436, 6196.318404975449, 5576.525995290446, 5885.646861188342, 5887.882242355215, 5901.668840807943, 5927.229460170151, 5680.77276908919, 5746.563848489551, 5759.191075452044, 5776.277965254343, 5798.5651076168615, 6100.23747031621, 6101.420365065312, 6125.938740612537, 6162.630242185483, 6183.237777233467, 4109.2808758467745, 4145.378193270913, 4165.91668166641, 4183.309753890574, 4186.539480657186, 5860.882782344414, 5879.911801322898, 5881.441263080001, 5893.5874488785375, 5899.557533185114, 5702.515286200553, 5709.857253568694, 5713.54304652311, 5741.91387129185, 5764.99112655292, 5513.476219759116, 5635.746391412315, 5647.644565304379, 5655.745491452977, 5676.77292908279, 5607.5513958884, 5660.235868157911, 5680.573956953328, 5691.516221121251, 5731.828045158652, 5449.264349312951, 5652.519535839499, 5722.284714410699, 5751.338596061507, 5756.971215144065, 5499.532539734168, 5522.751476183501, 5553.4724201200825, 5556.444355564411, 5568.248286481273], "value": 4343.021879677074, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.2654399608414, 745.4818900863899, 781.4100354232554, 798.3294033012269, 801.138775815998, 15817.920910395445, 16387.918060409713, 16448.177591120442, 16520.917395413035, 16570.83429165708, 15264.778817694585, 15533.301001454933, 15536.61158471036, 15550.53348399547, 15564.53306400807, 692.4322055914153, 716.6595866963203, 803.2329125684986, 804.6419343392192, 819.3813670678629, 820.5774026376421, 826.6197549127409, 826.9875951860727, 827.4001349021971, 828.5981299069977, 887.3744010472634, 887.7514295997131, 888.2183678363064, 889.9911000889973, 890.875277461156, 877.368294827723, 884.7080463447079, 884.9557522123868, 885.2254277507182, 886.264400547544, 647.7117682631231, 648.1282674802384, 651.0592194279268, 652.9510286728514, 653.1802587752388, 14663.266836658231, 14765.261736913219, 14773.630659233606, 14909.254537273202, 15353.462628808049, 14432.422703091865, 14706.852931470656, 15204.08775473464, 15223.467178648694, 15223.619409514851, 12038.879611203864, 12052.45763940616, 12118.768109448307, 12163.452644631012, 12415.31715755633, 3774.8867533974026, 3795.335816232176, 3810.618938106195, 3832.559255685604, 3945.5265368156006, 83548.0, 85941.57029214877, 86716.03853202739, 86862.13137868667, 87344.0, 10376.42929638862, 10483.266171368083, 10754.085902698202, 10768.56925722978, 11196.0, 21308.0, 21480.24819131323, 21585.67621485679, 21730.456738581663, 21816.563668726852, 7813.648385822652, 7899.170587088388, 7953.960230198812, 7968.721094761656, 8317.918670572908, 4377.693561450732, 4467.620252278528, 4485.147821913826, 4497.977510112429, 4566.452025756864, 3592.9640703593154, 3658.396364599811, 3668.5047518585184, 3713.4986776785327, 3727.7390582658954, 617.3641149615903, 619.7861737700487, 623.7598524567983, 629.1820633176852, 644.1175589442494, 715.9713611455587, 719.363363423374, 740.6000759589762, 744.9702011919571, 760.9733659321897, 8601.698940537051, 8764.255038321815, 8776.0, 8889.0, 9089.68240085954, 682.1950098883365, 682.4847240333517, 705.6894966214849, 705.9258777828356, 710.5843081797103, 4026.859059932888, 4203.936940945889, 4260.169266992969, 4300.204462174508, 4317.771241732494, 79612.0, 81898.0, 82608.86955652313, 82825.0, 84000.0, 58102.709486452295, 59963.400365996655, 69385.30614693889, 73722.0, 73768.63115684387, 52414.0, 52615.73692131515, 59072.0, 59323.0, 60021.69989150026, 9224.169824715807, 9580.13778759915, 9721.951390243003, 9745.707628771148, 9787.85318220213, 41439.7928010358, 42444.0, 42951.35572966345, 43629.78185109054, 45813.541864580926, 50912.745436272584, 51603.74198128985, 52182.47817521776, 52375.47624523706, 54314.0, 12637.304948227937, 12789.488420463449, 12843.871561284268, 13066.80399794023, 13103.14829536077, 12965.546205882749, 13101.344932753134, 13712.108712933627, 13906.860931390556, 14144.434222631391, 13394.464221431033, 14033.023571172405, 14370.13779173253, 14535.927320363331, 14763.261836908327, 13039.543615973396, 13192.340382981005, 13327.200367977564, 13636.0, 13676.111052781538, 11835.408229588314, 13951.651208719457, 14210.502632407815, 14273.14361138335, 14448.927755361156, 10494.370337779757, 10689.839652405064, 10841.295315804447, 10995.175361847734, 11179.385133817717, 10193.898061019296, 11594.623102263848, 12247.265164090182, 12302.384880756106, 12710.555130570385], "value": 7222.556076997138, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 111.0, 111.0, 112.0, 112.0, 112.0, 110.0, 112.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 124.0, 172.0, 123.0, 121.0, 121.0, 122.0, 142.0, 126.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 165.0, 165.0, 162.0, 165.0, 166.0, 163.0, 162.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 100.0, 79.0, 76.0, 76.0, 76.0, 105.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 108.0, 84.0, 83.0, 83.0, 105.0, 84.0, 82.0, 105.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 120.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 122.0], "value": 118.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 148.0, 149.0, 147.0, 146.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 98.0, 97.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 62.0, 60.0, 58.0, 58.0, 58.0, 58.0, 57.0, 58.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 68.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 82.0, 81.0, 81.0, 82.0, 87.0, 82.0, 80.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 134.0, 126.0, 126.0, 128.0, 146.0, 127.0, 133.0, 168.0, 131.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 172.0, 168.0, 166.0, 166.0, 167.0, 171.0, 169.0, 166.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 67.0, 63.0, 63.0, 62.0, 67.0, 63.0, 62.0, 62.0], "value": 63.7, "unit": "score"}]}]} 14:42:35 INFO - Return code: 0 14:42:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:42:35 ERROR - # TBPL SUCCESS # 14:42:35 INFO - Running post-action listener: _resource_record_post_action 14:42:35 INFO - [mozharness: 2016-12-29 22:42:35.570017Z] Finished run-tests step (success) 14:42:35 INFO - Running post-run listener: _resource_record_post_run 14:42:35 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 411668480; Read time: 396; Write time: 292240 14:42:35 INFO - TinderboxPrint: CPU usage
13.4% 14:42:35 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 396 14:42:35 INFO - TinderboxPrint: I/O write bytes / time
411,668,480 / 292,240 14:42:35 INFO - TinderboxPrint: CPU idle
8,248.6 (86.6%) 14:42:35 INFO - TinderboxPrint: CPU user
1,217.3 (12.8%) 14:42:35 INFO - TinderboxPrint: Swap in / out
0 / 0 14:42:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 116887552; Read time: 176; Write time: 79252 14:42:35 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294780928; Read time: 200; Write time: 212988 14:42:36 INFO - Running post-run listener: _upload_blobber_files 14:42:36 INFO - Blob upload gear active. 14:42:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:42:36 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 14:42:36 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:42:36 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:42:36 INFO - (blobuploader) - INFO - Open directory for files ... 14:42:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:42:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:42:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:37 INFO - (blobuploader) - INFO - Done attempting. 14:42:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:42:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:38 INFO - (blobuploader) - INFO - Done attempting. 14:42:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:42:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:42:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:41 INFO - (blobuploader) - INFO - Done attempting. 14:42:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:42:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:42:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:42 INFO - (blobuploader) - INFO - Done attempting. 14:42:42 INFO - (blobuploader) - INFO - Iteration through files over. 14:42:42 INFO - Return code: 0 14:42:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:42:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:42:42 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4a8482822cb7758c4812ef7f0a114248274379d525ce393b4780552a9ef53edcc65045b59487b58c0d999e0ac16bcb09d185190c821ec1ab16c782c3c7a86c54", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c74cffc292dfce2a91885c4a4700427c6caa16ef1c03c7534634097312a883f28b714cc4cba7d644a015120e75bc40e8aa880979c6d2350af2ce0c27483f88b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef2047f38e5050acdc83edcf2f83f530b9d435baf168dc90e6195cd1dcae4e7a111bc4927438878d687449e300eed0fd12197bd9d3f44243591992244a659b95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/921f807f90e19e845dc2d6f29cce03ca270dec99f1cd6cf6cc86dec69714df238d82300a5eb5d681c0bc3f253279d855a2caa1dc74dbf9fb8c98a7a25d52730b"} 14:42:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:42:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:42:42 INFO - Contents: 14:42:42 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4a8482822cb7758c4812ef7f0a114248274379d525ce393b4780552a9ef53edcc65045b59487b58c0d999e0ac16bcb09d185190c821ec1ab16c782c3c7a86c54", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c74cffc292dfce2a91885c4a4700427c6caa16ef1c03c7534634097312a883f28b714cc4cba7d644a015120e75bc40e8aa880979c6d2350af2ce0c27483f88b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef2047f38e5050acdc83edcf2f83f530b9d435baf168dc90e6195cd1dcae4e7a111bc4927438878d687449e300eed0fd12197bd9d3f44243591992244a659b95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/921f807f90e19e845dc2d6f29cce03ca270dec99f1cd6cf6cc86dec69714df238d82300a5eb5d681c0bc3f253279d855a2caa1dc74dbf9fb8c98a7a25d52730b"} 14:42:42 INFO - Running post-run listener: copy_logs_to_upload_dir 14:42:42 INFO - Copying logs to upload dir... 14:42:42 INFO - mkdir: /builds/slave/test/build/upload/logs 14:42:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.620304 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-29 14:42:42.187924) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 14:42:42.191425) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4a8482822cb7758c4812ef7f0a114248274379d525ce393b4780552a9ef53edcc65045b59487b58c0d999e0ac16bcb09d185190c821ec1ab16c782c3c7a86c54", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c74cffc292dfce2a91885c4a4700427c6caa16ef1c03c7534634097312a883f28b714cc4cba7d644a015120e75bc40e8aa880979c6d2350af2ce0c27483f88b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef2047f38e5050acdc83edcf2f83f530b9d435baf168dc90e6195cd1dcae4e7a111bc4927438878d687449e300eed0fd12197bd9d3f44243591992244a659b95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/921f807f90e19e845dc2d6f29cce03ca270dec99f1cd6cf6cc86dec69714df238d82300a5eb5d681c0bc3f253279d855a2caa1dc74dbf9fb8c98a7a25d52730b"} build_url:https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011735 build_url: 'https://queue.taskcluster.net/v1/task/B04dDzYaRUWk5gB05lFRjA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4a8482822cb7758c4812ef7f0a114248274379d525ce393b4780552a9ef53edcc65045b59487b58c0d999e0ac16bcb09d185190c821ec1ab16c782c3c7a86c54", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c74cffc292dfce2a91885c4a4700427c6caa16ef1c03c7534634097312a883f28b714cc4cba7d644a015120e75bc40e8aa880979c6d2350af2ce0c27483f88b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ef2047f38e5050acdc83edcf2f83f530b9d435baf168dc90e6195cd1dcae4e7a111bc4927438878d687449e300eed0fd12197bd9d3f44243591992244a659b95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/921f807f90e19e845dc2d6f29cce03ca270dec99f1cd6cf6cc86dec69714df238d82300a5eb5d681c0bc3f253279d855a2caa1dc74dbf9fb8c98a7a25d52730b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 14:42:42.216802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 14:42:42.217143) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1483047913.722830-272956252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 14:42:42.299968) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 14:42:42.300370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 14:42:42.300657) ========= ========= Total master_lag: 0.17 =========