builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1471563065.69 results: success (0) buildid: 20160818151256 builduid: 17c2596109c1484ca7d93a01b8052a2b revision: f8f422cda9d58189d100489111128f450c0bf581 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.687397) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.687848) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.688117) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.702762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.703162) ========= 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=923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 16:31:05-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.9M=0s 2016-08-18 16:31:06 (36.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075097 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.792074) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.792383) ========= 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=923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018301 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:05.841591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 16:31:05.841945) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8f422cda9d58189d100489111128f450c0bf581 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f8f422cda9d58189d100489111128f450c0bf581 --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=923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 16:31:06,123 truncating revision to first 12 chars 2016-08-18 16:31:06,123 Setting DEBUG logging. 2016-08-18 16:31:06,123 attempt 1/10 2016-08-18 16:31:06,123 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8f422cda9d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 16:31:07,195 unpacking tar archive at: mozilla-central-f8f422cda9d5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384097 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 16:31:07.240349) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:07.240691) ========= script_repo_revision: f8f422cda9d58189d100489111128f450c0bf581 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:07.241113) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:07.241426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 16:31:07.253172) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-18 16:31:07.253572) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:31:07 INFO - MultiFileLogger online at 20160818 16:31:07 in /builds/slave/test 16:31:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:31:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:07 INFO - {'append_to_log': False, 16:31:07 INFO - 'base_work_dir': '/builds/slave/test', 16:31:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:31:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:07 INFO - 'branch': 'Firefox-Non-PGO', 16:31:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:07 INFO - 'config_files': ('talos/linux_config.py',), 16:31:07 INFO - 'default_actions': ('clobber', 16:31:07 INFO - 'read-buildbot-config', 16:31:07 INFO - 'download-and-extract', 16:31:07 INFO - 'populate-webroot', 16:31:07 INFO - 'create-virtualenv', 16:31:07 INFO - 'install', 16:31:07 INFO - 'run-tests'), 16:31:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:07 INFO - 'download_minidump_stackwalk': True, 16:31:07 INFO - 'download_symbols': 'ondemand', 16:31:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:07 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:07 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:07 INFO - 'installer_path': 'installer.exe', 16:31:07 INFO - 'log_level': 'info', 16:31:07 INFO - 'log_name': 'talos', 16:31:07 INFO - 'log_to_console': True, 16:31:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:31:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:31:07 INFO - 'opt_config_files': (), 16:31:07 INFO - 'pip_index': False, 16:31:07 INFO - 'sps_profile': False, 16:31:07 INFO - 'sps_profile_interval': 0, 16:31:07 INFO - 'suite': 'dromaeojs', 16:31:07 INFO - 'system_bits': '32', 16:31:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:07 INFO - 'title': 'talos-linux64-ix-007', 16:31:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:07 INFO - 'use_talos_json': True, 16:31:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:07 INFO - 'work_dir': 'build'} 16:31:07 INFO - [mozharness: 2016-08-18 23:31:07.627009Z] Running clobber step. 16:31:07 INFO - Running pre-action listener: _resource_record_pre_action 16:31:07 INFO - Running main action method: clobber 16:31:07 INFO - rmtree: /builds/slave/test/build 16:31:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:31:08 INFO - Running post-action listener: _resource_record_post_action 16:31:08 INFO - [mozharness: 2016-08-18 23:31:08.616450Z] Finished clobber step (success) 16:31:08 INFO - [mozharness: 2016-08-18 23:31:08.616550Z] Running read-buildbot-config step. 16:31:08 INFO - Running pre-action listener: _resource_record_pre_action 16:31:08 INFO - Running main action method: read_buildbot_config 16:31:08 INFO - Using buildbot properties: 16:31:08 INFO - { 16:31:08 INFO - "project": "", 16:31:08 INFO - "product": "firefox", 16:31:08 INFO - "script_repo_revision": "production", 16:31:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:31:08 INFO - "repository": "", 16:31:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:31:08 INFO - "buildid": "20160818151256", 16:31:08 INFO - "pgo_build": "False", 16:31:08 INFO - "basedir": "/builds/slave/test", 16:31:08 INFO - "buildnumber": 683, 16:31:08 INFO - "slavename": "talos-linux64-ix-007", 16:31:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:31:08 INFO - "platform": "ubuntu64_hw", 16:31:08 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:31:08 INFO - "branch": "mozilla-central", 16:31:08 INFO - "repo_path": "mozilla-central", 16:31:08 INFO - "revision": "f8f422cda9d58189d100489111128f450c0bf581", 16:31:08 INFO - "stage_platform": "linux64", 16:31:08 INFO - "builduid": "17c2596109c1484ca7d93a01b8052a2b", 16:31:08 INFO - "slavebuilddir": "test" 16:31:08 INFO - } 16:31:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:31:08 INFO - Running post-action listener: _resource_record_post_action 16:31:08 INFO - [mozharness: 2016-08-18 23:31:08.618971Z] Finished read-buildbot-config step (success) 16:31:08 INFO - [mozharness: 2016-08-18 23:31:08.619059Z] Running download-and-extract step. 16:31:08 INFO - Running pre-action listener: _resource_record_pre_action 16:31:08 INFO - Running main action method: download_and_extract 16:31:08 INFO - mkdir: /builds/slave/test/build/tests 16:31:08 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')]} 16:31:08 INFO - trying https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:31:08 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:31:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:31:09 INFO - Downloaded 1361 bytes. 16:31:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:31:09 INFO - Using the following test package requirements: 16:31:09 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:31:09 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:31:09 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:31:09 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'jsshell-linux-x86_64.zip'], 16:31:09 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:31:09 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:31:09 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:31:09 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:31:09 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:31:09 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:31:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:31:09 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')]} 16:31:09 INFO - trying https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:31:09 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:31:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:31:10 INFO - Downloaded 20317262 bytes. 16:31:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 16:31:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:14 INFO - trying https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:31:14 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:31:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:31:15 INFO - Downloaded 20317262 bytes. 16:31:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 16:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:19 INFO - trying https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:31:19 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:31:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:31:20 INFO - Downloaded 14413981 bytes. 16:31:20 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 16:31:20 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')]} 16:31:20 INFO - trying https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:31:20 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:31:21 INFO - Downloaded 55766160 bytes. 16:31:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:31:21 INFO - mkdir: /builds/slave/test/properties 16:31:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:21 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:21 INFO - Contents: 16:31:21 INFO - build_url:https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:31:22 INFO - Running post-action listener: _resource_record_post_action 16:31:22 INFO - Running post-action listener: set_extra_try_arguments 16:31:22 INFO - [mozharness: 2016-08-18 23:31:22.608311Z] Finished download-and-extract step (success) 16:31:22 INFO - [mozharness: 2016-08-18 23:31:22.608535Z] Running populate-webroot step. 16:31:22 INFO - Running pre-action listener: _resource_record_pre_action 16:31:22 INFO - Running main action method: populate_webroot 16:31:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:22 INFO - '--tppagecycles', 16:31:22 INFO - '7'], 16:31:22 INFO - 'tests': ['tsvgm']}}, 16:31:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['damp', 'tps']}, 16:31:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'tests': ['damp', 'tps']}, 16:31:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['dromaeo_dom']}, 16:31:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['basic_compositor_video']}, 16:31:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:31:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['a11yr', 16:31:22 INFO - 'ts_paint', 16:31:22 INFO - 'tpaint', 16:31:22 INFO - 'sessionrestore', 16:31:22 INFO - 'sessionrestore_no_auto_restore', 16:31:22 INFO - 'tabpaint']}, 16:31:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:22 INFO - 'ts_paint', 16:31:22 INFO - 'tpaint', 16:31:22 INFO - 'sessionrestore', 16:31:22 INFO - 'sessionrestore_no_auto_restore', 16:31:22 INFO - 'tabpaint']}, 16:31:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:22 INFO - 'ts_paint', 16:31:22 INFO - 'tpaint', 16:31:22 INFO - 'sessionrestore', 16:31:22 INFO - 'sessionrestore_no_auto_restore', 16:31:22 INFO - 'tabpaint']}, 16:31:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:22 INFO - 'ts_paint', 16:31:22 INFO - 'tpaint', 16:31:22 INFO - 'sessionrestore', 16:31:22 INFO - 'sessionrestore_no_auto_restore', 16:31:22 INFO - 'tabpaint']}, 16:31:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['a11yr', 16:31:22 INFO - 'ts_paint', 16:31:22 INFO - 'tpaint', 16:31:22 INFO - 'sessionrestore', 16:31:22 INFO - 'sessionrestore_no_auto_restore', 16:31:22 INFO - 'tabpaint']}, 16:31:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['a11yr', 16:31:22 INFO - 'ts_paint', 16:31:22 INFO - 'tpaint', 16:31:22 INFO - 'sessionrestore', 16:31:22 INFO - 'sessionrestore_no_auto_restore', 16:31:22 INFO - 'tabpaint']}, 16:31:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['tsvgx', 16:31:22 INFO - 'tsvgr_opacity', 16:31:22 INFO - 'tart', 16:31:22 INFO - 'tscrollx', 16:31:22 INFO - 'cart']}, 16:31:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:22 INFO - 'tsvgr_opacity', 16:31:22 INFO - 'tart', 16:31:22 INFO - 'tscrollx', 16:31:22 INFO - 'cart']}, 16:31:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'talos_options': ['--disable-e10s'], 16:31:22 INFO - 'tests': ['tp5o']}, 16:31:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'tests': ['tp5o']}, 16:31:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'talos_options': ['--disable-e10s', 16:31:22 INFO - '--xperf_path', 16:31:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:22 INFO - 'tests': ['tp5n']}, 16:31:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:22 INFO - 'talos_options': ['--xperf_path', 16:31:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:22 INFO - 'tests': ['tp5n']}}, 16:31:22 INFO - 'talos.zip': {'path': '', 16:31:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:22 INFO - Running post-action listener: _resource_record_post_action 16:31:22 INFO - [mozharness: 2016-08-18 23:31:22.624633Z] Finished populate-webroot step (success) 16:31:22 INFO - [mozharness: 2016-08-18 23:31:22.624721Z] Running create-virtualenv step. 16:31:22 INFO - Running pre-action listener: _resource_record_pre_action 16:31:22 INFO - Running main action method: create_virtualenv 16:31:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:22 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 16:31:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:22 INFO - Using real prefix '/usr' 16:31:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:25 INFO - Installing pip.................done. 16:31:25 INFO - Return code: 0 16:31:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:25 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')]} 16:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:25 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')]} 16:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:25 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:25 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 16:31:25 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 16:31:25 INFO - Using env: {'DISPLAY': ':0', 16:31:25 INFO - 'HOME': '/home/cltbld', 16:31:25 INFO - 'LANG': 'en_US.UTF-8', 16:31:25 INFO - 'LANGUAGE': 'en_US:en', 16:31:25 INFO - 'LOGNAME': 'cltbld', 16:31:25 INFO - 'MAIL': '/var/mail/cltbld', 16:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:25 INFO - 'MOZ_NO_REMOTE': '1', 16:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:25 INFO - 'NO_EM_RESTART': '1', 16:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:25 INFO - 'PWD': '/builds/slave/test', 16:31:25 INFO - 'SHELL': '/bin/bash', 16:31:25 INFO - 'SHLVL': '1', 16:31:25 INFO - 'TERM': 'linux', 16:31:25 INFO - 'TMOUT': '86400', 16:31:25 INFO - 'USER': 'cltbld', 16:31:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:25 INFO - '_': '/tools/buildbot/bin/python'} 16:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:25 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 16:31:25 INFO - Cleaning up... 16:31:25 INFO - Return code: 0 16:31:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:25 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')]} 16:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:25 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')]} 16:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:25 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:25 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 16:31:25 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 16:31:25 INFO - Using env: {'DISPLAY': ':0', 16:31:25 INFO - 'HOME': '/home/cltbld', 16:31:25 INFO - 'LANG': 'en_US.UTF-8', 16:31:25 INFO - 'LANGUAGE': 'en_US:en', 16:31:25 INFO - 'LOGNAME': 'cltbld', 16:31:25 INFO - 'MAIL': '/var/mail/cltbld', 16:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:25 INFO - 'MOZ_NO_REMOTE': '1', 16:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:25 INFO - 'NO_EM_RESTART': '1', 16:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:25 INFO - 'PWD': '/builds/slave/test', 16:31:25 INFO - 'SHELL': '/bin/bash', 16:31:25 INFO - 'SHLVL': '1', 16:31:25 INFO - 'TERM': 'linux', 16:31:25 INFO - 'TMOUT': '86400', 16:31:25 INFO - 'USER': 'cltbld', 16:31:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:25 INFO - '_': '/tools/buildbot/bin/python'} 16:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:25 INFO - Downloading/unpacking psutil>=3.1.1 16:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:27 INFO - Installing collected packages: psutil 16:31:27 INFO - Running setup.py install for psutil 16:31:27 INFO - building 'psutil._psutil_linux' extension 16:31:27 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 16:31:27 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 16:31:27 INFO - building 'psutil._psutil_posix' extension 16:31:27 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 16:31:28 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 16:31:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:28 INFO - Successfully installed psutil 16:31:28 INFO - Cleaning up... 16:31:28 INFO - Return code: 0 16:31:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:31:28 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')]} 16:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:28 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')]} 16:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:28 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:28 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 16:31:28 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 16:31:28 INFO - Using env: {'DISPLAY': ':0', 16:31:28 INFO - 'HOME': '/home/cltbld', 16:31:28 INFO - 'LANG': 'en_US.UTF-8', 16:31:28 INFO - 'LANGUAGE': 'en_US:en', 16:31:28 INFO - 'LOGNAME': 'cltbld', 16:31:28 INFO - 'MAIL': '/var/mail/cltbld', 16:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:28 INFO - 'MOZ_NO_REMOTE': '1', 16:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:28 INFO - 'NO_EM_RESTART': '1', 16:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:28 INFO - 'PWD': '/builds/slave/test', 16:31:28 INFO - 'SHELL': '/bin/bash', 16:31:28 INFO - 'SHLVL': '1', 16:31:28 INFO - 'TERM': 'linux', 16:31:28 INFO - 'TMOUT': '86400', 16:31:28 INFO - 'USER': 'cltbld', 16:31:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:28 INFO - '_': '/tools/buildbot/bin/python'} 16:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:31:28 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 16:31:28 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 16:31:28 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 16:31:28 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 16:31:28 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 16:31:28 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 16:31:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:31:30 INFO - Installing collected packages: mozsystemmonitor 16:31:30 INFO - Running setup.py install for mozsystemmonitor 16:31:30 INFO - Successfully installed mozsystemmonitor 16:31:30 INFO - Cleaning up... 16:31:30 INFO - Return code: 0 16:31:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:31:30 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')]} 16:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:30 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')]} 16:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:30 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:30 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 16:31:30 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 16:31:30 INFO - Using env: {'DISPLAY': ':0', 16:31:30 INFO - 'HOME': '/home/cltbld', 16:31:30 INFO - 'LANG': 'en_US.UTF-8', 16:31:30 INFO - 'LANGUAGE': 'en_US:en', 16:31:30 INFO - 'LOGNAME': 'cltbld', 16:31:30 INFO - 'MAIL': '/var/mail/cltbld', 16:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:30 INFO - 'MOZ_NO_REMOTE': '1', 16:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:30 INFO - 'NO_EM_RESTART': '1', 16:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:30 INFO - 'PWD': '/builds/slave/test', 16:31:30 INFO - 'SHELL': '/bin/bash', 16:31:30 INFO - 'SHLVL': '1', 16:31:30 INFO - 'TERM': 'linux', 16:31:30 INFO - 'TMOUT': '86400', 16:31:30 INFO - 'USER': 'cltbld', 16:31:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:30 INFO - '_': '/tools/buildbot/bin/python'} 16:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:30 INFO - Downloading/unpacking jsonschema==2.5.1 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:31:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31:32 INFO - Downloading functools32-3.2.3-2.tar.gz 16:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:31:32 INFO - warning: no files found matching '*.txt' 16:31:32 INFO - no previously-included directories found matching 'build' 16:31:32 INFO - no previously-included directories found matching 'dist' 16:31:32 INFO - no previously-included directories found matching '.git*' 16:31:32 INFO - Installing collected packages: jsonschema, functools32 16:31:32 INFO - Running setup.py install for functools32 16:31:32 INFO - warning: no files found matching '*.txt' 16:31:32 INFO - no previously-included directories found matching 'build' 16:31:32 INFO - no previously-included directories found matching 'dist' 16:31:32 INFO - no previously-included directories found matching '.git*' 16:31:33 INFO - Successfully installed jsonschema functools32 16:31:33 INFO - Cleaning up... 16:31:33 INFO - Return code: 0 16:31:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:33 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:33 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 16:31:33 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 16:31:33 INFO - Using env: {'DISPLAY': ':0', 16:31:33 INFO - 'HOME': '/home/cltbld', 16:31:33 INFO - 'LANG': 'en_US.UTF-8', 16:31:33 INFO - 'LANGUAGE': 'en_US:en', 16:31:33 INFO - 'LOGNAME': 'cltbld', 16:31:33 INFO - 'MAIL': '/var/mail/cltbld', 16:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:33 INFO - 'MOZ_NO_REMOTE': '1', 16:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:33 INFO - 'NO_EM_RESTART': '1', 16:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:33 INFO - 'PWD': '/builds/slave/test', 16:31:33 INFO - 'SHELL': '/bin/bash', 16:31:33 INFO - 'SHLVL': '1', 16:31:33 INFO - 'TERM': 'linux', 16:31:33 INFO - 'TMOUT': '86400', 16:31:33 INFO - 'USER': 'cltbld', 16:31:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:33 INFO - '_': '/tools/buildbot/bin/python'} 16:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:31:33 INFO - Cleaning up... 16:31:33 INFO - Return code: 0 16:31:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:33 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:33 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 16:31:33 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 16:31:33 INFO - Using env: {'DISPLAY': ':0', 16:31:33 INFO - 'HOME': '/home/cltbld', 16:31:33 INFO - 'LANG': 'en_US.UTF-8', 16:31:33 INFO - 'LANGUAGE': 'en_US:en', 16:31:33 INFO - 'LOGNAME': 'cltbld', 16:31:33 INFO - 'MAIL': '/var/mail/cltbld', 16:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:33 INFO - 'MOZ_NO_REMOTE': '1', 16:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:33 INFO - 'NO_EM_RESTART': '1', 16:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:33 INFO - 'PWD': '/builds/slave/test', 16:31:33 INFO - 'SHELL': '/bin/bash', 16:31:33 INFO - 'SHLVL': '1', 16:31:33 INFO - 'TERM': 'linux', 16:31:33 INFO - 'TMOUT': '86400', 16:31:33 INFO - 'USER': 'cltbld', 16:31:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:33 INFO - '_': '/tools/buildbot/bin/python'} 16:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:33 INFO - Downloading/unpacking blobuploader==1.2.4 16:31:33 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 16:31:33 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 16:31:33 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 16:31:33 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 16:31:33 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 16:31:33 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 16:31:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:31:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:31:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 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 16:31:35 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 16:31:36 INFO - Downloading docopt-0.6.1.tar.gz 16:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:31:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:31:36 INFO - Running setup.py install for blobuploader 16:31:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:31:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:31:36 INFO - Running setup.py install for requests 16:31:36 INFO - Running setup.py install for docopt 16:31:36 INFO - Successfully installed blobuploader requests docopt 16:31:36 INFO - Cleaning up... 16:31:36 INFO - Return code: 0 16:31:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:36 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:36 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 16:31:36 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 16:31:36 INFO - Using env: {'DISPLAY': ':0', 16:31:36 INFO - 'HOME': '/home/cltbld', 16:31:36 INFO - 'LANG': 'en_US.UTF-8', 16:31:36 INFO - 'LANGUAGE': 'en_US:en', 16:31:36 INFO - 'LOGNAME': 'cltbld', 16:31:36 INFO - 'MAIL': '/var/mail/cltbld', 16:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:36 INFO - 'MOZ_NO_REMOTE': '1', 16:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:36 INFO - 'NO_EM_RESTART': '1', 16:31:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:36 INFO - 'PWD': '/builds/slave/test', 16:31:36 INFO - 'SHELL': '/bin/bash', 16:31:36 INFO - 'SHLVL': '1', 16:31:36 INFO - 'TERM': 'linux', 16:31:36 INFO - 'TMOUT': '86400', 16:31:36 INFO - 'USER': 'cltbld', 16:31:36 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:36 INFO - '_': '/tools/buildbot/bin/python'} 16:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:36 INFO - Running setup.py (path:/tmp/pip-3g0WQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:37 INFO - Running setup.py (path:/tmp/pip-BPygDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:37 INFO - Running setup.py (path:/tmp/pip-yyhsxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:37 INFO - Running setup.py (path:/tmp/pip-eP5mfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:37 INFO - Running setup.py (path:/tmp/pip-LnWbaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:37 INFO - Running setup.py (path:/tmp/pip-FhUUeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:37 INFO - Running setup.py (path:/tmp/pip-qMeSfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:37 INFO - Running setup.py (path:/tmp/pip-Ohhx_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:37 INFO - Running setup.py (path:/tmp/pip-E48lRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:37 INFO - Running setup.py (path:/tmp/pip-9HIOFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:37 INFO - Running setup.py (path:/tmp/pip-5MzjFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:37 INFO - Running setup.py (path:/tmp/pip-rKDpqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:37 INFO - Running setup.py (path:/tmp/pip-Fyzm6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:37 INFO - Running setup.py (path:/tmp/pip-HvcuJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:37 INFO - Running setup.py (path:/tmp/pip-Hu2RxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:38 INFO - Running setup.py (path:/tmp/pip-WSX6S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:38 INFO - Running setup.py (path:/tmp/pip-b2qP8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:31:38 INFO - Running setup.py install for manifestparser 16:31:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Running setup.py install for mozcrash 16:31:38 INFO - Running setup.py install for mozdebug 16:31:38 INFO - Running setup.py install for mozdevice 16:31:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Running setup.py install for mozfile 16:31:38 INFO - Running setup.py install for mozhttpd 16:31:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Running setup.py install for mozinfo 16:31:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Running setup.py install for mozInstall 16:31:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:31:38 INFO - Running setup.py install for mozleak 16:31:39 INFO - Running setup.py install for mozlog 16:31:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Running setup.py install for moznetwork 16:31:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Running setup.py install for mozprocess 16:31:39 INFO - Running setup.py install for mozprofile 16:31:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Running setup.py install for mozrunner 16:31:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Running setup.py install for mozscreenshot 16:31:39 INFO - Running setup.py install for moztest 16:31:39 INFO - Running setup.py install for mozversion 16:31:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:31:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:31:39 INFO - Cleaning up... 16:31:39 INFO - Return code: 0 16:31:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:39 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')]} 16:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:39 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')]} 16:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:39 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:39 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 16:31:39 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 16:31:39 INFO - Using env: {'DISPLAY': ':0', 16:31:39 INFO - 'HOME': '/home/cltbld', 16:31:39 INFO - 'LANG': 'en_US.UTF-8', 16:31:39 INFO - 'LANGUAGE': 'en_US:en', 16:31:39 INFO - 'LOGNAME': 'cltbld', 16:31:39 INFO - 'MAIL': '/var/mail/cltbld', 16:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:39 INFO - 'MOZ_NO_REMOTE': '1', 16:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:39 INFO - 'NO_EM_RESTART': '1', 16:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:39 INFO - 'PWD': '/builds/slave/test', 16:31:39 INFO - 'SHELL': '/bin/bash', 16:31:39 INFO - 'SHLVL': '1', 16:31:39 INFO - 'TERM': 'linux', 16:31:39 INFO - 'TMOUT': '86400', 16:31:39 INFO - 'USER': 'cltbld', 16:31:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:39 INFO - '_': '/tools/buildbot/bin/python'} 16:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:40 INFO - Running setup.py (path:/tmp/pip-YjR67j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:40 INFO - Running setup.py (path:/tmp/pip-CBbSBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:40 INFO - Running setup.py (path:/tmp/pip-7TQZly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:40 INFO - Running setup.py (path:/tmp/pip-889FKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:40 INFO - Running setup.py (path:/tmp/pip-s8nyDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:40 INFO - Running setup.py (path:/tmp/pip-sb9phM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:40 INFO - Running setup.py (path:/tmp/pip-Zsh7Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:40 INFO - Running setup.py (path:/tmp/pip-CknEAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:40 INFO - Running setup.py (path:/tmp/pip-C1H1A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:40 INFO - Running setup.py (path:/tmp/pip-EjW59R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:40 INFO - Running setup.py (path:/tmp/pip-dqFjvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:40 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)) 16:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:40 INFO - Running setup.py (path:/tmp/pip-KPCUSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:41 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)) 16:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:41 INFO - Running setup.py (path:/tmp/pip-pV1SUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:41 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)) 16:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:41 INFO - Running setup.py (path:/tmp/pip-RlZqvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:41 INFO - Running setup.py (path:/tmp/pip-zY9w6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:41 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)) 16:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:41 INFO - Running setup.py (path:/tmp/pip-QdmhJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:41 INFO - Running setup.py (path:/tmp/pip-S_XjhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:41 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)) 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:31:41 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)) 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:41 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 16:31:41 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 16:31:41 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 16:31:41 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 16:31:41 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 16:31:41 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 16:31:43 INFO - Downloading blessings-1.6.tar.gz 16:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:31:43 INFO - Installing collected packages: blessings 16:31:43 INFO - Running setup.py install for blessings 16:31:43 INFO - Successfully installed blessings 16:31:43 INFO - Cleaning up... 16:31:43 INFO - Return code: 0 16:31:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:43 INFO - Reading from file tmpfile_stdout 16:31:43 INFO - Current package versions: 16:31:43 INFO - argparse == 1.2.1 16:31:43 INFO - blessings == 1.6 16:31:43 INFO - blobuploader == 1.2.4 16:31:43 INFO - docopt == 0.6.1 16:31:43 INFO - functools32 == 3.2.3-2 16:31:43 INFO - jsonschema == 2.5.1 16:31:43 INFO - manifestparser == 1.1 16:31:43 INFO - mozInstall == 1.12 16:31:43 INFO - mozcrash == 0.17 16:31:43 INFO - mozdebug == 0.1 16:31:43 INFO - mozdevice == 0.48 16:31:43 INFO - mozfile == 1.2 16:31:43 INFO - mozhttpd == 0.7 16:31:43 INFO - mozinfo == 0.9 16:31:43 INFO - mozleak == 0.1 16:31:43 INFO - mozlog == 3.3 16:31:43 INFO - moznetwork == 0.27 16:31:43 INFO - mozprocess == 0.23 16:31:43 INFO - mozprofile == 0.28 16:31:43 INFO - mozrunner == 6.12 16:31:43 INFO - mozscreenshot == 0.1 16:31:43 INFO - mozsystemmonitor == 0.3 16:31:43 INFO - moztest == 0.7 16:31:43 INFO - mozversion == 1.4 16:31:43 INFO - psutil == 3.1.1 16:31:43 INFO - requests == 1.2.3 16:31:43 INFO - wsgiref == 0.1.2 16:31:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:43 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')]} 16:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:43 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')]} 16:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:43 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:43 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 16:31:43 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 16:31:43 INFO - Using env: {'DISPLAY': ':0', 16:31:43 INFO - 'HOME': '/home/cltbld', 16:31:43 INFO - 'LANG': 'en_US.UTF-8', 16:31:43 INFO - 'LANGUAGE': 'en_US:en', 16:31:43 INFO - 'LOGNAME': 'cltbld', 16:31:43 INFO - 'MAIL': '/var/mail/cltbld', 16:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:43 INFO - 'MOZ_NO_REMOTE': '1', 16:31:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:43 INFO - 'NO_EM_RESTART': '1', 16:31:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:43 INFO - 'PWD': '/builds/slave/test', 16:31:43 INFO - 'SHELL': '/bin/bash', 16:31:43 INFO - 'SHLVL': '1', 16:31:43 INFO - 'TERM': 'linux', 16:31:43 INFO - 'TMOUT': '86400', 16:31:43 INFO - 'USER': 'cltbld', 16:31:43 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:43 INFO - '_': '/tools/buildbot/bin/python'} 16:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 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)) 16:31:43 INFO - Cleaning up... 16:31:44 INFO - Return code: 0 16:31:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:44 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 0x7fa9a486bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e3230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, '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': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', '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 16:31:44 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 16:31:44 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 16:31:44 INFO - Using env: {'DISPLAY': ':0', 16:31:44 INFO - 'HOME': '/home/cltbld', 16:31:44 INFO - 'LANG': 'en_US.UTF-8', 16:31:44 INFO - 'LANGUAGE': 'en_US:en', 16:31:44 INFO - 'LOGNAME': 'cltbld', 16:31:44 INFO - 'MAIL': '/var/mail/cltbld', 16:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:44 INFO - 'MOZ_NO_REMOTE': '1', 16:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:44 INFO - 'NO_EM_RESTART': '1', 16:31:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:44 INFO - 'PWD': '/builds/slave/test', 16:31:44 INFO - 'SHELL': '/bin/bash', 16:31:44 INFO - 'SHLVL': '1', 16:31:44 INFO - 'TERM': 'linux', 16:31:44 INFO - 'TMOUT': '86400', 16:31:44 INFO - 'USER': 'cltbld', 16:31:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:44 INFO - '_': '/tools/buildbot/bin/python'} 16:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:31:44 INFO - Cleaning up... 16:31:44 INFO - Return code: 0 16:31:44 INFO - Running post-action listener: _resource_record_post_action 16:31:44 INFO - Running post-action listener: _start_resource_monitoring 16:31:44 INFO - Starting resource monitoring. 16:31:44 INFO - [mozharness: 2016-08-18 23:31:44.255139Z] Finished create-virtualenv step (success) 16:31:44 INFO - [mozharness: 2016-08-18 23:31:44.255644Z] Running install step. 16:31:44 INFO - Running pre-action listener: _resource_record_pre_action 16:31:44 INFO - Running main action method: install 16:31:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:44 INFO - Reading from file tmpfile_stdout 16:31:44 INFO - Detecting whether we're running mozinstall >=1.0... 16:31:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:31:44 INFO - Reading from file tmpfile_stdout 16:31:44 INFO - Output received: 16:31:44 INFO - Usage: mozinstall [options] installer 16:31:44 INFO - Options: 16:31:44 INFO - -h, --help show this help message and exit 16:31:44 INFO - -d DEST, --destination=DEST 16:31:44 INFO - Directory to install application into. [default: 16:31:44 INFO - "/builds/slave/test"] 16:31:44 INFO - --app=APP Application being installed. [default: firefox] 16:31:44 INFO - mkdir: /builds/slave/test/build/application 16:31:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:32:00 INFO - Reading from file tmpfile_stdout 16:32:00 INFO - Output received: 16:32:00 INFO - /builds/slave/test/build/application/firefox/firefox 16:32:00 INFO - Running post-action listener: _resource_record_post_action 16:32:00 INFO - [mozharness: 2016-08-18 23:32:00.702944Z] Finished install step (success) 16:32:00 INFO - [mozharness: 2016-08-18 23:32:00.703192Z] Running run-tests step. 16:32:00 INFO - Running pre-action listener: _resource_record_pre_action 16:32:00 INFO - Running main action method: run_tests 16:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:00 INFO - Python 2.7.3 16:32:00 INFO - Return code: 0 16:32:00 INFO - grabbing minidump binary from tooltool 16:32:00 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')]} 16:32:00 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 0x27dd090>, '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 0x27e09b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e0e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:00 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 16:32:00 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 16:32:00 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 16:32:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:00 INFO - Return code: 0 16:32:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:32:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:32:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:32:00 INFO - Using env: {'DISPLAY': ':0', 16:32:00 INFO - 'HOME': '/home/cltbld', 16:32:00 INFO - 'LANG': 'en_US.UTF-8', 16:32:00 INFO - 'LANGUAGE': 'en_US:en', 16:32:00 INFO - 'LOGNAME': 'cltbld', 16:32:00 INFO - 'MAIL': '/var/mail/cltbld', 16:32:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:00 INFO - 'MOZ_NO_REMOTE': '1', 16:32:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:00 INFO - 'NO_EM_RESTART': '1', 16:32:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:00 INFO - 'PWD': '/builds/slave/test', 16:32:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:00 INFO - 'SHELL': '/bin/bash', 16:32:00 INFO - 'SHLVL': '1', 16:32:00 INFO - 'TERM': 'linux', 16:32:00 INFO - 'TMOUT': '86400', 16:32:00 INFO - 'USER': 'cltbld', 16:32:00 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387', 16:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:00 INFO - '_': '/tools/buildbot/bin/python'} 16:32:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:32:01 INFO - mozversion application_buildid: 20160818151256 16:32:01 INFO - mozversion application_changeset: f8f422cda9d58189d100489111128f450c0bf581 16:32:01 INFO - mozversion application_display_name: Nightly 16:32:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:01 INFO - mozversion application_name: Firefox 16:32:01 INFO - mozversion application_remotingname: firefox 16:32:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:32:01 INFO - mozversion application_vendor: Mozilla 16:32:01 INFO - mozversion application_version: 51.0a1 16:32:01 INFO - mozversion platform_buildid: 20160818151256 16:32:01 INFO - mozversion platform_changeset: f8f422cda9d58189d100489111128f450c0bf581 16:32:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:32:01 INFO - mozversion platform_version: 51.0a1 16:32:01 INFO - using testdate: 1471563121 16:32:01 INFO - actual date: 1471563121 16:32:01 INFO - starting webserver on 'localhost:47390' 16:32:01 INFO - SUITE-START | Running 2 tests 16:32:01 INFO - TEST-START | dromaeo_css 16:32:01 INFO - operating with platform_type : linux_ 16:32:01 INFO - Initialising browser for dromaeo_css test... 16:32:01 INFO - TEST-INFO | started process 19155 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp21CCA0/profile http://localhost:47390/getInfo.html) 16:32:05 INFO - PROCESS | 19155 | __metrics Screen width/height:1600/1200 16:32:05 INFO - PROCESS | 19155 | colorDepth:24 16:32:05 INFO - PROCESS | 19155 | Browser inner width/height: 1024/697 16:32:05 INFO - PROCESS | 19155 | __metrics 16:32:05 INFO - PROCESS | 19155 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:32:05 INFO - PROCESS | 19155 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:06 INFO - PROCESS | 19155 | console.error: PushService: 16:32:06 INFO - PROCESS | 19155 | stateChangeProcessEnqueue: Error transitioning state 16:32:06 INFO - PROCESS | 19155 | UnknownError 16:32:06 INFO - TEST-INFO | 19155: exit 0 16:32:06 INFO - Browser initialized. 16:32:06 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:06 INFO - TEST-INFO | started process 19235 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp21CCA0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:18 INFO - PROCESS | 19235 | RSS: Main: 157413376 16:32:18 INFO - PROCESS | 19235 | 16:33:09 INFO - PROCESS | 19235 | 1471563189495 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:35:22 INFO - PROCESS | 19235 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47390/tests/dromaeo/cssquery-ext.html) 16:35:23 INFO - PROCESS | 19235 | RSS: Main: 241471488 16:35:23 INFO - PROCESS | 19235 | 16:38:26 INFO - PROCESS | 19235 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-ext.html (next: http://localhost:47390/tests/dromaeo/cssquery-jquery.html) 16:38:27 INFO - PROCESS | 19235 | RSS: Main: 248664064 16:38:27 INFO - PROCESS | 19235 | 16:41:29 INFO - PROCESS | 19235 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47390/tests/dromaeo/cssquery-mootools.html) 16:41:29 INFO - PROCESS | 19235 | RSS: Main: 191574016 16:41:29 INFO - PROCESS | 19235 | 16:44:32 INFO - PROCESS | 19235 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47390/tests/dromaeo/cssquery-prototype.html) 16:44:32 INFO - PROCESS | 19235 | RSS: Main: 199757824 16:44:32 INFO - PROCESS | 19235 | 16:47:35 INFO - PROCESS | 19235 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47390/tests/dromaeo/cssquery-yui.html) 16:47:36 INFO - PROCESS | 19235 | RSS: Main: 190308352 16:47:36 INFO - PROCESS | 19235 | 16:50:39 INFO - PROCESS | 19235 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-yui.html (next: http://localhost:47390/tests/dromaeo/cssquery-dojo.html) 16:50:40 INFO - PROCESS | 19235 | RSS: Main: 271994880 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | __start_tp_report 16:50:40 INFO - PROCESS | 19235 | _x_x_mozilla_page_load 16:50:40 INFO - PROCESS | 19235 | _x_x_mozilla_page_load_details 16:50:40 INFO - PROCESS | 19235 | |i|pagename|runs| 16:50:40 INFO - PROCESS | 19235 | |0;dojo.html;2573.2280315905227;2583.6641236639234;2595.0657763205245;2615.3069436599294;2621.9868900655492;4481.238189507783;4514.074614703986;4622.607078398336;4678.485366609674;4689.5310468953085;4552.112338094073;4616.746078965655;4634.745089020103;4645.210314246578;4732.242841145408;4224.746515209097;4243.32106862902;4271.252530807112;4309.245881970643;4377;4291.141771645668;4345.196138714348;4398.966242932925;4418.911621767563;4434.268345722952;4304.935425968613;4342.066455712005;4343.239933011726;4358.97820510897;4379.277419225824;4159.542450330461;4162.6253637172495;4189.97905010473;4254.1279037797485;4277.743335399886;4189.601987811153;4201.621854033121;4206.116715489751;4207.621314081747;4232.132412855357;4615.053913947632;4618.9076218475775;4642.605378542828;4646.697964632322;4659.091477161955;4775.832845850413;4901.338319326881;4962.751862406865;4965.0566392385335;4973.204287314049;5014.097462456756;5027.195648696227;5031.22016087507;5047.116754567936;5082.542571168613;4673.439187297546;4744.833930812405;4747.8100875965265;4751.501092385319;4752.025834703877;4953.628477864103;4969.925451118236;4996.375453068366;4997.925031124536;5088.160453525127;4850.83022094225;4889.217725164028;4890.046440943982;4890.242012487999;4893.4617192108835;4929.06347793918;4934.9753251233515;4968.8757781055765;4974.726390048582;4975.651704380731;4723.102610504062;4814.470408255089;4855.514448555187;4862.051899879561;4918.065567542156;4864.36763220779;4876.512348765166;4879.658423910363;4883.682560633619;4927.77824997876;4118.361653943643;4125.91748165035;4127.050778321003;4139.875803725893;4166.937495937564;3566.964330356715;3592.1558433768046;3592.604813470516;3621.6378362163573;3659.451082337671;4319.179355922364;4321.848735294249;4321.978390108029;4341.544137865541;4358.084802191575;4957.157283261854;4957.182064959241;4959.107360675041;4961.032598643302;4996.775145118479;4244.915101697949;4283.828646854154;4334.089841133335;4335.588119128678;4345.739255644671;4441.666874984389;4461.977690111594;4464.129494748493;4493.483249426293;4505.301678239878;4719.032598317268;4721.244600863845;4724.976375118103;4725.5510726480925;4762.3332733416655;4836.903261934812;4919.286703428054;4924.064427758714;4928.236123400879;4991.101601711727;4699.036697477078;4708.717476951394;4713.410823647044;4751;4758.762061896822;4843.539863712942;4847.030593881308;4850.46644869057;4852.587530059844;4861.44093429246;4746.311784791117;4900.485449027943;4948.975255123702;4952.306677065286;4978.352814134139;4776.593989510791;4830.7826147824135;4839.346688197189;4872.074305881871;4885.145099607484;4810.158222311012;4821.734804585782;4831.782569784438;4835.29888166221;4883.511648835088;4536.9092618148115;4637.698549594265;4663.626909847137;4673.6728429011;4742.644301677319;4828.975855120842;4855.951440485691;4859.441164266154;4885.609151267961;4910.705357678551;4723.929141062956;4724.078804633064;4729.077829823152;4729.385179926588;4761.928571071506 16:50:40 INFO - PROCESS | 19235 | |1;ext.html;566.8611190258388;580.2688612348439;585.5227989188811;585.9912101318483;585.9941400585994;28835.85582072089;38929.80535097328;40062.399064014025;40141.38727514523;44228;40716.59283407165;41132.177356452856;41134;41170.58829411705;41193.176136477254;7619.923800761977;7749.418793590475;7758.030246219223;7760.456768026241;7762.068551773795;7359.852802943965;9688.370255933341;9970.052844979715;9973.850392244121;10334.276600637959;9769.658061967833;9852.211823054138;9859.90140098597;9861;9871.259655525828;9910.454924979123;9969.551370188286;9983.101520863085;9991.950040249825;9996.600135994551;9565.808683826353;9631.133198012143;9639.373440726331;9643.132118109404;9643.180329671986;38994.22011559781;39331.213375732324;39342;39360.803195984125;39419.802900985596;2924.2396976785976;3078.7690923180626;3261.1684020574603;3344.7324214062814;3732.6640602345647;28785.28036799076;30490.84754576235;30988.690113098804;31996.360072798645;32219.677803222137;3306;3427.2288735034667;3450.9482357764655;3470.23654795947;3598.6041535431345;303647;309453;309624;428884;429044;58104;58811.35452391162;58827.11759323614;58904.7054764732;58938.705306473195;34002.31995360079;35262.47106293408;35269.29461410757;61766.69116654388;62109;13022;13129;13136.60590182296;13143.67140821468;13171.143875648053;9812.26408019387;9857.359271647463;10710.08964238033;10885.782284354269;10890;18834.905825470785;19406.599610738933;19435.73667711583;19470.046655371287;19600.313989010316;7128.429725621938;7194.784156475314;7362.44781641379;7517.849643007109;7537.585432801248;734.181387752652;810.6676262732251;922.7969846633864;929.8651695504115;989.7674046599044;26509.867450662623;28153;28580.714192857806;28610.570841437395;28666;920.4661296448044;925.717656114878;949.2121539122512;961.6874515782399;1032.0195813976752;14570.562883113524;14840.40638374453;15119.395224191128;15399.92300038515;16030.519084427486;398323;400147;400302;401166;401167;51421.48578514242;51710;51884.48115518872;51916.22125668118;51984;1073.4739977411168;1085.5440714899603;1090.8909109089027;1145.1468655851277;1185.105245539612;20880.58238835245;28355.858220708764;28879;31303.217419564695;32010.67989320077;45810;46606;47158.528414715416;47160.52839471699;47231;332871;333432;333556;333936;334477;22334.329970100924;22383.32850014502;22911.656325154803;25179.370515737253;25760.61359079578;39717.2056558873;40091;40532.79733601313;41067.383989240785;41236.58763412327;34455;34695.65304347025;36442.63557364392;37486.2502749938;37985.81007094947;36743.26513469662;36806;37333.253334933695;37494.625053749114;37497.62502374942;35898.8205058973;36222.45666314955;36280.81859590685;36326;36503;31965.68034319627;32661;33710;33811.15472113217;34195.14512137217;25237.369065773502;25899.741002589733;26213.606795898457;26304.473910522065;26682.332941675835 16:50:40 INFO - PROCESS | 19235 | |2;jquery.html;356.8947160587628;360.77992424620976;364.5716283367044;370.5941993517098;382.9770213787173;16021;17000.91499542502;17209.82790172098;17358.913205433953;17364;16268;16304.51086467408;16815.91592042041;16861.494155175365;17009.57476063096;4704.506026867181;4937.210046392578;4939.110960027194;4941.925871111936;4944.60443164546;3761.717871159674;3904.629060239273;4901.558859702626;5785.450382213683;6047.788327408542;6051.1830902828215;6060.484858787008;6061.787837425691;6063.575549711522;6067.9696601516935;5788.739506722187;5875;5914;5919.911201331984;5932.139839723238;4705.482396936334;5205.739713014372;5207.661502002357;5210.921836172461;5222.190560463135;12511.249325040526;13999.930000349934;19959.700604490943;20803.5839283215;20813.791862081336;1819.5360183153343;1839.062078340043;1854.7866995295444;2014.174188582674;2222.155580879272;8756.124387561256;9112.726618201465;9182.357234993553;9395.248380129573;9566.186874115709;1617.1509957272526;1659.1538315459081;1695.9745603815952;1705.360489816319;1777.4489908128521;69493.65253173752;69953.65023174851;70036.6498167511;71958.64020679916;72364.63817680877;9808.46053467052;10014.24893133018;10084.546195421077;10161.745956351151;10279.126274266622;13572.457101716018;13586.796198057036;13662.931685341708;13663;13668.384922678304;6906.136732908387;7136.536125151849;7178.820529486805;7188.748393806296;7450.292222238879;6353.428191462791;6392.776252831129;6566.113574667359;6624.304448032887;6626.734930602722;4263.722858014281;4329.285667864767;4330.501992270866;4387.2103021455805;4434.246178149722;1594.4499147793993;1740.3908631978707;1768.2308195934552;1809.1316168239318;1829.1768704083215;857.446946719375;910.7222297199418;929.8326301265702;933.2021121940643;935.7473482159779;16557;16862.662746744998;16880.493585192464;17304.826951730574;18075.72886406705;813.8331642013374;842.2630198576246;848.8005318750089;865.9826803463895;913.8400779863497;9296.39573427725;9816.263780216512;9846.409215446953;9902.306838521234;10032.1472674822;357585;362661;363171;363823;363980;74094.62952685202;74473.25526744772;75433.62283188548;76660.61669691616;76969;857.8578530498172;870.3428911172025;897.6813231302924;897.7710683775596;897.7845317123844;14895.47865824691;14969.46723542417;15335.313115557057;15473;15473.613159671098;27483.725162748116;35815.46276805798;37058;37198.44202336913;38239;48922.26616600825;50708.492915070376;50991.490085098674;51273.487265126874;51415;7039.436845052486;7056.682449289691;7372.262773722585;7608.0489938757655;7810.960945195237;10567.418791966516;11087.611933582608;11088.667339979813;11148.609798657008;11290.322580645188;9718.368306060083;9843.85234221473;10091.697249082246;10356.637517686844;10367.377957322586;9809.950950245204;9908.851367229641;10166.135878450414;10183;10317;9965.850512242178;10031;10228.693139205836;10338.6737983953;10574.418406987383;7109.466789990668;7296.562206267641;7462.738804141829;7484.850302994018;7495;8896.332775042027;9731.513424328898;9898.851517227391;9986.60053597877;10320.741981450223 16:50:40 INFO - PROCESS | 19235 | |3;mootools.html;1364.3178410794603;1394.9023568350215;1469.742795010873;1637.0750525952844;1676.4886709553589;7547.6603552840115;8118.14759450258;8128.471649342803;8134.105248422668;8137.226963438471;6778;7291.8906216406795;7463.962680186605;7507.24927507249;8038.758837234877;2104.3055791588777;2135.1672847589452;2136.4552039229975;2136.4552039230052;2150.9354719358444;2108.399106254718;2146.720926279589;2214.0700905619597;2214.6235140026233;2217.1131547381015;2175.6410192318326;2177.129148340661;2180.6401943679275;2185.846990710651;2221.911123555064;2079.1683326669304;2094.361219827952;2097.8531502794813;2108.98945505273;2114.217739436403;2095.8113769760644;2145.1205005947486;2147.849650524464;2149.8925053747407;2150.236665983569;7698.730544430918;7766.57283849388;8050.677972881078;8158.347332213409;8160.591970401516;4369.08249267651;4487.551244875519;4488.438945131858;4494.595486406239;4496.662750293741;2772.875220615057;2894.52240380337;2938.750206232473;2946.6611339695783;2954.9556756648667;897.6185121323412;943.9244860411151;954.1794057110949;956.7273327101742;964.6045121500222;10602.04581587653;10605.681829545127;10615.628453004108;10624.09695175911;10636.57453701851;3963.365861462152;4207.0113523322;4221.746695198298;4247.447831781848;4332.718373305725;9023.548822558845;9045.819083618291;9048;9053.366264361432;9064.50145242018;4386.627136693353;4416.514183439819;4418.845340413134;4424.977875110668;4427.911441771146;3556.8577256909434;3965.325894597925;4071.816768245377;4075.3071977763852;4119.505659320901;3606.4770608261656;3627.927441451209;3746.6066063062985;3746.8875933722034;3752.9249415011545;1146.7821113988316;1166.2069792540983;1173.976520469586;1175.988240117605;1197.0543270815958;738.2433006168612;853.807893224032;866.9479831210148;873.6549063120032;900.7297810656777;5391.56867450603;5444.128939369682;5551.2228288039705;5633.042382794934;5689.260396148474;398.26122542683487;411.6727201874517;412.36495796473696;414.6123374644717;419.8803341047787;3833.2333533293454;3855.6722678572073;3892.9805350973065;3997.661952269887;4025.5571887092397;9126.08739126082;9369.48467834262;9392.483413412192;9413.152816246571;9422.528873556432;9244.121808428188;9355.48544830041;9355.859662105075;9357.90642093584;9770.169535589555;7147.2852714728115;7303.123625165014;7318.085239345082;7331.083614548182;7367.594782286918;5396.9730151348995;5609.719514024364;5662.037453632975;5731.426857314235;5850.824475265749;8795.164459376349;9070.229030532215;9164.404313719588;9246.95376523113;9267.76830579241;8746.562671866508;9051;9118.133777291147;9400.15398614128;9406.623735050794;6710.094137291599;6866.382025617719;6893.551919125241;7074.221835597978;7077;7690.307872291521;7746.573938433215;7748.65131069092;7753.534787912743;7755.457118001911;6658.800235992928;6664.83337916556;6665.700043498151;6703.765368212089;6996.335348141918;6704.597724136567;6781.6270105144695;6826.522143450109;6881.311868813079;6974.128233970754;6532.183477065367;6636.7013484392355;6636.7345306189145;6640;6643.269240383651;4645.674802763774;4798.640101992295;4840.1287768200655;4849.684770489906;4934.481879402611;4724.338592597108;4814.566688998007;4907.214845624683;4930.4822993586595;4942.184539550934 16:50:40 INFO - PROCESS | 19235 | |4;prototype.html;294.4493796600358;302.23082255659347;306.9248034231613;307.5525110963548;310.93314937288494;4861.5935112119505;4913.11563918495;4919.557239848413;4937.629677774173;4959.479254678261;4609.953900460994;4647.186742320097;4804.855854324367;4809.759512024402;4867.3672422585105;3803.619638036202;3806.29583527047;3812.7712337259854;3817.675497582695;3818.579956204815;3796.5823759386444;3807.809609519527;3818.8472461101524;3834.7537050458573;3838.539375274971;3633.6003039665616;3654.543182102237;3766.3973764197735;3771.283456143338;3802.6387493188104;3816.9618303816883;3820;3823.617638236168;3824.368979118442;3838.3282925488206;3375.29118885032;3384.120128766514;3386.5258863759095;3388.8813891513923;3413.3173365327034;4575.382323386333;4754.334393184957;4759.547842954914;4796.400449943757;4820.373351464323;4284.335927931175;4289.2922667759785;4292.656587472995;4295.797176790504;4334.609885110323;5057.443681195065;5061.848144555669;5091.61812864033;5113.51421614946;5153.896922061538;3593.8921832345072;3600.513930619359;3617.276544691072;3624.6012938576737;3635.745497815154;5151.330327057459;5256.632035757537;5305.575553955713;5321.175217841241;5321.361436627591;3640.199156185635;3651.561812582507;3660.3228402745044;3667.8166091695307;3675.831259556874;3728.3475391806323;3730.4963829883163;3732.64539868712;3737.271232109767;3747.2318174774105;4144.544100148981;4239.689369293742;4312.310030395121;4313.805878735402;4326.199653064194;4996.326965073741;5017.498250174954;5024.145895197825;5044.445111037783;5046.34397528319;1215.9331236782061;1268.460904115747;1284.7045179608742;1285.7592423311362;1294.9223046617233;860.9496414374453;874.9387542871938;881.1232823340816;884.9778755531163;886.4636894678755;4436.933445998313;4472.373867658531;4476.216662084123;4480.283154695233;4507.5350511084025;5173.405058418255;5181.870453238624;5188.8962220755375;5200.959808038408;5206.453322401093;4378.277584198571;4486.3494793254795;4505.279155335196;4523.683342166082;4536.841210557614;2743.1633351827486;2753.931151721183;2773.1680495851165;2773.611694362791;2774.5838124281518;4920.138975679313;4929.482404347492;4929.531694489017;4936.925946110811;4944.802207911644;5114.107780121304;5324.565655152483;5352.759125839347;5376.91934620981;5379.892402151935;4958.752062396866;4965.453800081988;4973.875653108701;4974.776135073858;5005.849824505198;5299.788008479618;5311.760970756248;5313.2561441397465;5330;5356.723392558225;4889.413270407574;5099.69401835891;5155.200943853709;5167.509086636764;5170.1986192140275;4971.776270067783;4994.9750251248515;5010.273510341053;5012.248162775612;5025.522575355335;3799.316123097869;3818.4081467372603;3818.7517811342173;3832.5400951885954;3840.4431357453573;5074.695518268915;5208.192730126836;5208.8437346879655;5213.582913366846;5228;5028.622853285945;5032.245163225545;5035.615205818429;5037.471065538212;5081.923771143362;5027.930211383772;5053.898922021613;5063.13926632466;5066.316047333563;5093.752030752489;4709.317149013443;4857.441394239567;4920.188168952082;4920.827771027997;4934.138199844154;4969.35398398206;5102.158143906214;5112.130937740668;5125.3353851996;5149.639525233198;4986.626003049713;5002.549770520671;5012.598992080698;5024.447310795883;5030.9245361320345 16:50:40 INFO - PROCESS | 19235 | |5;yui.html;614.2782230878718;649.3928177154364;658.8319978405506;700.982475438114;713.9647511108893;11829.940850295747;12698.746025079492;12827.16623419477;12850.357482125892;12854.742905141891;12330.63008109758;12457.314847683374;12556.811647825267;12572;12584.433700483478;609.2506217352659;744.0773440933258;749.7450866705319;768.1435199752292;770.4722265248323;768.2816566510303;769.5728870476883;770.7649167004062;771.178694690156;771.9266669666373;799.7280924485674;803.2569872867574;803.3091541274499;804.3364224514769;805.0701439836969;648.1153225846709;773.9071311442607;796.8844517544982;796.9083555391118;801.2988634944423;583.9416058394169;587.4213899309219;588.3087372337529;588.3116753398534;588.9676067816268;11848.17062805604;11857.644270671894;11873.703157421047;11876.287422754576;11896.881031189663;11404.315741055563;11578.884211157865;11694.55837300047;11758.470868810839;12915.87084129156;10183.89816101837;10276.12268564803;11550.480228389742;11824.23142495735;11993.220440671328;3116.2520994961274;3195.760317976161;3229.3864165808423;3267.477203647405;3412.4540073588355;60704.39295607032;62208;63284.36715632877;63384.68307658432;64525.3547464524;9532.142107210384;9765.169960553432;10074.244431667508;10096.14182794456;10249.12882404989;18935;19244.23023079089;19265.8073419264;19282.13230404635;19354.032298385024;6116.424843293477;6333.398327158912;6374.330695277022;6379.55343125986;6384.361563843671;3638.9818050909576;3704.2961837250837;3780.9243815123546;3783.375743002429;3792.1088544192094;2953.5126704094014;3056.449839028996;3077.199928018731;3081.245094951751;3087.768417368706;475.2064053031446;494.82433736023916;500.3345550417935;511.307178772765;529.0292313604579;444.96885218034424;497.42547357801794;512.9333186685782;520.687587447536;553.1536748774382;7618.657160427794;7784.571848548384;7841.255080767318;8213.178682131738;8213.44260834708;535.6625326044567;539.8920215956824;547.2366049361199;550.5678042736432;557.5623135838348;3657.6708096271464;3679.816009199529;3691.667749902522;3797.3164830330807;3814.3324918139215;105447.47276263569;105794.47102764437;107606;108829;110108;70983.2901670987;74344.62827685828;82203;82210.17789822145;83648;63322;70499;71110.28889711243;72535.637321812;74242;8456.412688112956;8768.473891566526;8806.929587252787;8843.248323892542;9086.591103400231;47550.76224618855;48257.51742482671;49058.509414906825;49274;49335.259971099746;61478.69260653847;68510.65744671444;69533;69561;70238.64880675563;10564.207684423545;10705.250632455653;11168.21822472419;11645;12183.878161218629;11659.475323610628;11679.591214307458;11766.572777403117;11931.761364772483;12285.262884226975;8648.351373646876;9962.651307204213;10102.584858614979;11351.432428378714;11354.772904542027;11582.536698531965;12032.27806331623;12263.632091037283;12538.87461125377;12634.431450584929;10632.84050739255;10644.308119972176;10919.83620245681;10958.45207739594;11274.605388811353;8768.167024132697;8856.158664926821;9040.230975953315;9108.77228069277;9409.29430292717;9027.45835249887;9520.143187113194;9737.561809718722;9819.803603928025;9820.361676491291 16:50:40 INFO - PROCESS | 19235 | __end_tp_report 16:50:40 INFO - PROCESS | 19235 | __start_cc_report 16:50:40 INFO - PROCESS | 19235 | _x_x_mozilla_cycle_collect,943 16:50:40 INFO - PROCESS | 19235 | __end_cc_report 16:50:40 INFO - PROCESS | 19235 | __startTimestamp1471564240090__endTimestamp 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | ------- Summary: start ------- 16:50:40 INFO - PROCESS | 19235 | Number of tests: 6 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | [#0] dojo.html Cycles:165 Average:4576.67 Median:4724.98 stddev:474.77 (10.0%) stddev-sans-first:449.46 16:50:40 INFO - PROCESS | 19235 | Values: 2573.2 2583.7 2595.1 2615.3 2622.0 4481.2 4514.1 4622.6 4678.5 4689.5 4552.1 4616.7 4634.7 4645.2 4732.2 4224.7 4243.3 4271.3 4309.2 4377.0 4291.1 4345.2 4399.0 4418.9 4434.3 4304.9 4342.1 4343.2 4359.0 4379.3 4159.5 4162.6 4190.0 4254.1 4277.7 4189.6 4201.6 4206.1 4207.6 4232.1 4615.1 4618.9 4642.6 4646.7 4659.1 4775.8 4901.3 4962.8 4965.1 4973.2 5014.1 5027.2 5031.2 5047.1 5082.5 4673.4 4744.8 4747.8 4751.5 4752.0 4953.6 4969.9 4996.4 4997.9 5088.2 4850.8 4889.2 4890.0 4890.2 4893.5 4929.1 4935.0 4968.9 4974.7 4975.7 4723.1 4814.5 4855.5 4862.1 4918.1 4864.4 4876.5 4879.7 4883.7 4927.8 4118.4 4125.9 4127.1 4139.9 4166.9 3567.0 3592.2 3592.6 3621.6 3659.5 4319.2 4321.8 4322.0 4341.5 4358.1 4957.2 4957.2 4959.1 4961.0 4996.8 4244.9 4283.8 4334.1 4335.6 4345.7 4441.7 4462.0 4464.1 4493.5 4505.3 4719.0 4721.2 4725.0 4725.6 4762.3 4836.9 4919.3 4924.1 4928.2 4991.1 4699.0 4708.7 4713.4 4751.0 4758.8 4843.5 4847.0 4850.5 4852.6 4861.4 4746.3 4900.5 4949.0 4952.3 4978.4 4776.6 4830.8 4839.3 4872.1 4885.1 4810.2 4821.7 4831.8 4835.3 4883.5 4536.9 4637.7 4663.6 4673.7 4742.6 4829.0 4856.0 4859.4 4885.6 4910.7 4723.9 4724.1 4729.1 4729.4 4761.9 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | [#1] ext.html Cycles:180 Average:51516.79 Median:37741.72 stddev:96534.85 (255.8%) stddev-sans-first:96729.86 16:50:40 INFO - PROCESS | 19235 | Values: 566.9 580.3 585.5 586.0 586.0 28835.9 38929.8 40062.4 40141.4 44228.0 40716.6 41132.2 41134.0 41170.6 41193.2 7619.9 7749.4 7758.0 7760.5 7762.1 7359.9 9688.4 9970.1 9973.9 10334.3 9769.7 9852.2 9859.9 9861.0 9871.3 9910.5 9969.6 9983.1 9992.0 9996.6 9565.8 9631.1 9639.4 9643.1 9643.2 38994.2 39331.2 39342.0 39360.8 39419.8 2924.2 3078.8 3261.2 3344.7 3732.7 28785.3 30490.8 30988.7 31996.4 32219.7 3306.0 3427.2 3450.9 3470.2 3598.6 303647.0 309453.0 309624.0 428884.0 429044.0 58104.0 58811.4 58827.1 58904.7 58938.7 34002.3 35262.5 35269.3 61766.7 62109.0 13022.0 13129.0 13136.6 13143.7 13171.1 9812.3 9857.4 10710.1 10885.8 10890.0 18834.9 19406.6 19435.7 19470.0 19600.3 7128.4 7194.8 7362.4 7517.8 7537.6 734.2 810.7 922.8 929.9 989.8 26509.9 28153.0 28580.7 28610.6 28666.0 920.5 925.7 949.2 961.7 1032.0 14570.6 14840.4 15119.4 15399.9 16030.5 398323.0 400147.0 400302.0 401166.0 401167.0 51421.5 51710.0 51884.5 51916.2 51984.0 1073.5 1085.5 1090.9 1145.1 1185.1 20880.6 28355.9 28879.0 31303.2 32010.7 45810.0 46606.0 47158.5 47160.5 47231.0 332871.0 333432.0 333556.0 333936.0 334477.0 22334.3 22383.3 22911.7 25179.4 25760.6 39717.2 40091.0 40532.8 41067.4 41236.6 34455.0 34695.7 36442.6 37486.3 37985.8 36743.3 36806.0 37333.3 37494.6 37497.6 35898.8 36222.5 36280.8 36326.0 36503.0 31965.7 32661.0 33710.0 33811.2 34195.1 25237.4 25899.7 26213.6 26304.5 26682.3 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | [#2] jquery.html Cycles:180 Average:23329.64 Median:26911.91 stddev:60017.28 (223.0%) stddev-sans-first:60160.86 16:50:40 INFO - PROCESS | 19235 | Values: 356.9 360.8 364.6 370.6 383.0 16021.0 17000.9 17209.8 17358.9 17364.0 16268.0 16304.5 16815.9 16861.5 17009.6 4704.5 4937.2 4939.1 4941.9 4944.6 3761.7 3904.6 4901.6 5785.5 6047.8 6051.2 6060.5 6061.8 6063.6 6068.0 5788.7 5875.0 5914.0 5919.9 5932.1 4705.5 5205.7 5207.7 5210.9 5222.2 12511.2 13999.9 19959.7 20803.6 20813.8 1819.5 1839.1 1854.8 2014.2 2222.2 8756.1 9112.7 9182.4 9395.2 9566.2 1617.2 1659.2 1696.0 1705.4 1777.4 69493.7 69953.7 70036.6 71958.6 72364.6 9808.5 10014.2 10084.5 10161.7 10279.1 13572.5 13586.8 13662.9 13663.0 13668.4 6906.1 7136.5 7178.8 7188.7 7450.3 6353.4 6392.8 6566.1 6624.3 6626.7 4263.7 4329.3 4330.5 4387.2 4434.2 1594.4 1740.4 1768.2 1809.1 1829.2 857.4 910.7 929.8 933.2 935.7 16557.0 16862.7 16880.5 17304.8 18075.7 813.8 842.3 848.8 866.0 913.8 9296.4 9816.3 9846.4 9902.3 10032.1 357585.0 362661.0 363171.0 363823.0 363980.0 74094.6 74473.3 75433.6 76660.6 76969.0 857.9 870.3 897.7 897.8 897.8 14895.5 14969.5 15335.3 15473.0 15473.6 27483.7 35815.5 37058.0 37198.4 38239.0 48922.3 50708.5 50991.5 51273.5 51415.0 7039.4 7056.7 7372.3 7608.0 7811.0 10567.4 11087.6 11088.7 11148.6 11290.3 9718.4 9843.9 10091.7 10356.6 10367.4 9810.0 9908.9 10166.1 10183.0 10317.0 9965.9 10031.0 10228.7 10338.7 10574.4 7109.5 7296.6 7462.7 7484.9 7495.0 8896.3 9731.5 9898.9 9986.6 10320.7 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | [#3] mootools.html Cycles:180 Average:5173.98 Median:5394.27 stddev:2903.24 (53.8%) stddev-sans-first:2897.27 16:50:40 INFO - PROCESS | 19235 | Values: 1364.3 1394.9 1469.7 1637.1 1676.5 7547.7 8118.1 8128.5 8134.1 8137.2 6778.0 7291.9 7464.0 7507.2 8038.8 2104.3 2135.2 2136.5 2136.5 2150.9 2108.4 2146.7 2214.1 2214.6 2217.1 2175.6 2177.1 2180.6 2185.8 2221.9 2079.2 2094.4 2097.9 2109.0 2114.2 2095.8 2145.1 2147.8 2149.9 2150.2 7698.7 7766.6 8050.7 8158.3 8160.6 4369.1 4487.6 4488.4 4494.6 4496.7 2772.9 2894.5 2938.8 2946.7 2955.0 897.6 943.9 954.2 956.7 964.6 10602.0 10605.7 10615.6 10624.1 10636.6 3963.4 4207.0 4221.7 4247.4 4332.7 9023.5 9045.8 9048.0 9053.4 9064.5 4386.6 4416.5 4418.8 4425.0 4427.9 3556.9 3965.3 4071.8 4075.3 4119.5 3606.5 3627.9 3746.6 3746.9 3752.9 1146.8 1166.2 1174.0 1176.0 1197.1 738.2 853.8 866.9 873.7 900.7 5391.6 5444.1 5551.2 5633.0 5689.3 398.3 411.7 412.4 414.6 419.9 3833.2 3855.7 3893.0 3997.7 4025.6 9126.1 9369.5 9392.5 9413.2 9422.5 9244.1 9355.5 9355.9 9357.9 9770.2 7147.3 7303.1 7318.1 7331.1 7367.6 5397.0 5609.7 5662.0 5731.4 5850.8 8795.2 9070.2 9164.4 9247.0 9267.8 8746.6 9051.0 9118.1 9400.2 9406.6 6710.1 6866.4 6893.6 7074.2 7077.0 7690.3 7746.6 7748.7 7753.5 7755.5 6658.8 6664.8 6665.7 6703.8 6996.3 6704.6 6781.6 6826.5 6881.3 6974.1 6532.2 6636.7 6636.7 6640.0 6643.3 4645.7 4798.6 4840.1 4849.7 4934.5 4724.3 4814.6 4907.2 4930.5 4942.2 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | [#4] prototype.html Cycles:180 Average:4223.24 Median:4859.52 stddev:1222.79 (25.2%) stddev-sans-first:1190.13 16:50:40 INFO - PROCESS | 19235 | Values: 294.4 302.2 306.9 307.6 310.9 4861.6 4913.1 4919.6 4937.6 4959.5 4610.0 4647.2 4804.9 4809.8 4867.4 3803.6 3806.3 3812.8 3817.7 3818.6 3796.6 3807.8 3818.8 3834.8 3838.5 3633.6 3654.5 3766.4 3771.3 3802.6 3817.0 3820.0 3823.6 3824.4 3838.3 3375.3 3384.1 3386.5 3388.9 3413.3 4575.4 4754.3 4759.5 4796.4 4820.4 4284.3 4289.3 4292.7 4295.8 4334.6 5057.4 5061.8 5091.6 5113.5 5153.9 3593.9 3600.5 3617.3 3624.6 3635.7 5151.3 5256.6 5305.6 5321.2 5321.4 3640.2 3651.6 3660.3 3667.8 3675.8 3728.3 3730.5 3732.6 3737.3 3747.2 4144.5 4239.7 4312.3 4313.8 4326.2 4996.3 5017.5 5024.1 5044.4 5046.3 1215.9 1268.5 1284.7 1285.8 1294.9 860.9 874.9 881.1 885.0 886.5 4436.9 4472.4 4476.2 4480.3 4507.5 5173.4 5181.9 5188.9 5201.0 5206.5 4378.3 4486.3 4505.3 4523.7 4536.8 2743.2 2753.9 2773.2 2773.6 2774.6 4920.1 4929.5 4929.5 4936.9 4944.8 5114.1 5324.6 5352.8 5376.9 5379.9 4958.8 4965.5 4973.9 4974.8 5005.8 5299.8 5311.8 5313.3 5330.0 5356.7 4889.4 5099.7 5155.2 5167.5 5170.2 4971.8 4995.0 5010.3 5012.2 5025.5 3799.3 3818.4 3818.8 3832.5 3840.4 5074.7 5208.2 5208.8 5213.6 5228.0 5028.6 5032.2 5035.6 5037.5 5081.9 5027.9 5053.9 5063.1 5066.3 5093.8 4709.3 4857.4 4920.2 4920.8 4934.1 4969.4 5102.2 5112.1 5125.3 5149.6 4986.6 5002.5 5012.6 5024.4 5030.9 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - PROCESS | 19235 | [#5] yui.html Cycles:180 Average:17859.39 Median:505.82 stddev:26129.20 (5165.7%) stddev-sans-first:26170.42 16:50:40 INFO - PROCESS | 19235 | Values: 614.3 649.4 658.8 701.0 714.0 11829.9 12698.7 12827.2 12850.4 12854.7 12330.6 12457.3 12556.8 12572.0 12584.4 609.3 744.1 749.7 768.1 770.5 768.3 769.6 770.8 771.2 771.9 799.7 803.3 803.3 804.3 805.1 648.1 773.9 796.9 796.9 801.3 583.9 587.4 588.3 588.3 589.0 11848.2 11857.6 11873.7 11876.3 11896.9 11404.3 11578.9 11694.6 11758.5 12915.9 10183.9 10276.1 11550.5 11824.2 11993.2 3116.3 3195.8 3229.4 3267.5 3412.5 60704.4 62208.0 63284.4 63384.7 64525.4 9532.1 9765.2 10074.2 10096.1 10249.1 18935.0 19244.2 19265.8 19282.1 19354.0 6116.4 6333.4 6374.3 6379.6 6384.4 3639.0 3704.3 3780.9 3783.4 3792.1 2953.5 3056.4 3077.2 3081.2 3087.8 475.2 494.8 500.3 511.3 529.0 445.0 497.4 512.9 520.7 553.2 7618.7 7784.6 7841.3 8213.2 8213.4 535.7 539.9 547.2 550.6 557.6 3657.7 3679.8 3691.7 3797.3 3814.3 105447.5 105794.5 107606.0 108829.0 110108.0 70983.3 74344.6 82203.0 82210.2 83648.0 63322.0 70499.0 71110.3 72535.6 74242.0 8456.4 8768.5 8806.9 8843.2 9086.6 47550.8 48257.5 49058.5 49274.0 49335.3 61478.7 68510.7 69533.0 69561.0 70238.6 10564.2 10705.3 11168.2 11645.0 12183.9 11659.5 11679.6 11766.6 11931.8 12285.3 8648.4 9962.7 10102.6 11351.4 11354.8 11582.5 12032.3 12263.6 12538.9 12634.4 10632.8 10644.3 10919.8 10958.5 11274.6 8768.2 8856.2 9040.2 9108.8 9409.3 9027.5 9520.1 9737.6 9819.8 9820.4 16:50:40 INFO - PROCESS | 19235 | -------- Summary: end -------- 16:50:40 INFO - PROCESS | 19235 | 16:50:40 INFO - TEST-INFO | 19235: exit 0 16:50:40 INFO - TEST-OK | dromaeo_css | took 1119830ms 16:50:40 INFO - TEST-START | kraken 16:50:40 INFO - operating with platform_type : linux_ 16:50:40 INFO - Initialising browser for kraken test... 16:50:40 INFO - TEST-INFO | started process 19536 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2NCtWb/profile http://localhost:47390/getInfo.html) 16:50:44 INFO - PROCESS | 19536 | __metrics Screen width/height:1600/1200 16:50:44 INFO - PROCESS | 19536 | colorDepth:24 16:50:44 INFO - PROCESS | 19536 | Browser inner width/height: 1024/697 16:50:44 INFO - PROCESS | 19536 | __metrics 16:50:45 INFO - PROCESS | 19536 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:50:45 INFO - PROCESS | 19536 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:50:45 INFO - PROCESS | 19536 | console.error: PushService: 16:50:45 INFO - PROCESS | 19536 | stateChangeProcessEnqueue: Error transitioning state 16:50:45 INFO - PROCESS | 19536 | UnknownError 16:50:46 INFO - TEST-INFO | 19536: exit 0 16:50:46 INFO - Browser initialized. 16:50:46 INFO - Running cycle 1/1 for kraken test... 16:50:46 INFO - TEST-INFO | started process 19620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2NCtWb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:50:58 INFO - PROCESS | 19620 | RSS: Main: 156893184 16:50:58 INFO - PROCESS | 19620 | 16:51:01 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:02 INFO - PROCESS | 19620 | RSS: Main: 205365248 16:51:02 INFO - PROCESS | 19620 | 16:51:04 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-dft) 16:51:05 INFO - PROCESS | 19620 | RSS: Main: 196579328 16:51:05 INFO - PROCESS | 19620 | 16:51:08 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-fft) 16:51:09 INFO - PROCESS | 19620 | RSS: Main: 277975040 16:51:09 INFO - PROCESS | 19620 | 16:51:11 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-oscillator) 16:51:12 INFO - PROCESS | 19620 | RSS: Main: 187305984 16:51:12 INFO - PROCESS | 19620 | 16:51:14 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47390/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:14 INFO - PROCESS | 19620 | RSS: Main: 192724992 16:51:14 INFO - PROCESS | 19620 | 16:51:18 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47390/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:18 INFO - PROCESS | 19620 | RSS: Main: 229195776 16:51:18 INFO - PROCESS | 19620 | 16:51:22 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47390/tests/kraken/driver.html?testName=imaging-desaturate) 16:51:22 INFO - PROCESS | 19620 | RSS: Main: 222957568 16:51:22 INFO - PROCESS | 19620 | 16:51:25 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47390/tests/kraken/driver.html?testName=json-parse-financial) 16:51:26 INFO - PROCESS | 19620 | RSS: Main: 225759232 16:51:26 INFO - PROCESS | 19620 | 16:51:28 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47390/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:51:28 INFO - PROCESS | 19620 | RSS: Main: 189296640 16:51:28 INFO - PROCESS | 19620 | 16:51:30 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:51:31 INFO - PROCESS | 19620 | RSS: Main: 196329472 16:51:31 INFO - PROCESS | 19620 | 16:51:33 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:51:34 INFO - PROCESS | 19620 | RSS: Main: 194105344 16:51:34 INFO - PROCESS | 19620 | 16:51:36 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:37 INFO - PROCESS | 19620 | RSS: Main: 189562880 16:51:37 INFO - PROCESS | 19620 | 16:51:40 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:51:41 INFO - PROCESS | 19620 | RSS: Main: 192589824 16:51:41 INFO - PROCESS | 19620 | 16:51:42 INFO - PROCESS | 19620 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47390/tests/kraken/driver.html?testName=ai-astar) 16:51:43 INFO - PROCESS | 19620 | RSS: Main: 243322880 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | __start_tp_report 16:51:43 INFO - PROCESS | 19620 | _x_x_mozilla_page_load 16:51:43 INFO - PROCESS | 19620 | _x_x_mozilla_page_load_details 16:51:43 INFO - PROCESS | 19620 | |i|pagename|runs| 16:51:43 INFO - PROCESS | 19620 | |0;ai-astar;107;117;114;116;110;117;116;114;115;115 16:51:43 INFO - PROCESS | 19620 | |1;audio-beat-detection;149;121;125;190;124;122;152;123;191;128 16:51:43 INFO - PROCESS | 19620 | |2;audio-dft;160;161;161;161;160;160;160;161;160;160 16:51:43 INFO - PROCESS | 19620 | |3;audio-fft;76;76;76;104;76;78;77;76;102;77 16:51:43 INFO - PROCESS | 19620 | |4;audio-oscillator;87;86;144;88;86;87;116;86;87;123 16:51:43 INFO - PROCESS | 19620 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;116;117 16:51:43 INFO - PROCESS | 19620 | |6;imaging-darkroom;147;146;146;146;146;147;146;147;147;146 16:51:43 INFO - PROCESS | 19620 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;95;95 16:51:43 INFO - PROCESS | 19620 | |8;json-parse-financial;66;65;67;65;66;67;65;66;66;64 16:51:43 INFO - PROCESS | 19620 | |9;json-stringify-tinderbox;66;66;67;66;67;66;66;65;66;65 16:51:43 INFO - PROCESS | 19620 | |10;stanford-crypto-aes;83;83;83;83;83;83;82;81;82;83 16:51:43 INFO - PROCESS | 19620 | |11;stanford-crypto-ccm;136;135;129;134;131;129;133;140;149;135 16:51:43 INFO - PROCESS | 19620 | |12;stanford-crypto-pbkdf2;172;170;169;172;170;172;170;170;170;169 16:51:43 INFO - PROCESS | 19620 | |13;stanford-crypto-sha256-iterative;68;68;70;67;70;69;67;69;67;67 16:51:43 INFO - PROCESS | 19620 | __end_tp_report 16:51:43 INFO - PROCESS | 19620 | __start_cc_report 16:51:43 INFO - PROCESS | 19620 | _x_x_mozilla_cycle_collect,1944 16:51:43 INFO - PROCESS | 19620 | __end_cc_report 16:51:43 INFO - PROCESS | 19620 | __startTimestamp1471564303084__endTimestamp 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | ------- Summary: start ------- 16:51:43 INFO - PROCESS | 19620 | Number of tests: 14 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.50 stddev:3.21 (2.8%) stddev-sans-first:2.15 16:51:43 INFO - PROCESS | 19620 | Values: 107.0 117.0 114.0 116.0 110.0 117.0 116.0 114.0 115.0 115.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:138.50 stddev:27.61 (19.9%) stddev-sans-first:29.19 16:51:43 INFO - PROCESS | 19620 | Values: 149.0 121.0 125.0 190.0 124.0 122.0 152.0 123.0 191.0 128.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 16:51:43 INFO - PROCESS | 19620 | Values: 160.0 161.0 161.0 161.0 160.0 160.0 160.0 161.0 160.0 160.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:11.20 (14.7%) stddev-sans-first:11.68 16:51:43 INFO - PROCESS | 19620 | Values: 76.0 76.0 76.0 104.0 76.0 78.0 77.0 76.0 102.0 77.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#4] audio-oscillator Cycles:10 Average:99.00 Median:86.50 stddev:20.95 (24.2%) stddev-sans-first:21.77 16:51:43 INFO - PROCESS | 19620 | Values: 87.0 86.0 144.0 88.0 86.0 87.0 116.0 86.0 87.0 123.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:51:43 INFO - PROCESS | 19620 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:51:43 INFO - PROCESS | 19620 | Values: 147.0 146.0 146.0 146.0 146.0 147.0 146.0 147.0 147.0 146.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:51:43 INFO - PROCESS | 19620 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:66.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 16:51:43 INFO - PROCESS | 19620 | Values: 66.0 65.0 67.0 65.0 66.0 67.0 65.0 66.0 66.0 64.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 16:51:43 INFO - PROCESS | 19620 | Values: 66.0 66.0 67.0 66.0 67.0 66.0 66.0 65.0 66.0 65.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 16:51:43 INFO - PROCESS | 19620 | Values: 83.0 83.0 83.0 83.0 83.0 83.0 82.0 81.0 82.0 83.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#11] stanford-crypto-ccm Cycles:10 Average:135.10 Median:135.00 stddev:5.92 (4.4%) stddev-sans-first:6.26 16:51:43 INFO - PROCESS | 19620 | Values: 136.0 135.0 129.0 134.0 131.0 129.0 133.0 140.0 149.0 135.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:170.00 stddev:1.17 (0.7%) stddev-sans-first:1.09 16:51:43 INFO - PROCESS | 19620 | Values: 172.0 170.0 169.0 172.0 170.0 172.0 170.0 170.0 170.0 169.0 16:51:43 INFO - PROCESS | 19620 | 16:51:43 INFO - PROCESS | 19620 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:1.23 (1.8%) stddev-sans-first:1.30 16:51:43 INFO - PROCESS | 19620 | Values: 68.0 68.0 70.0 67.0 70.0 69.0 67.0 69.0 67.0 67.0 16:51:43 INFO - PROCESS | 19620 | -------- Summary: end -------- 16:51:43 INFO - PROCESS | 19620 | 16:51:44 INFO - TEST-INFO | 19620: exit 0 16:51:44 INFO - TEST-OK | kraken | took 63318ms 16:51:44 INFO - SUITE-END | took 1183s 16:51:44 INFO - Completed test suite (00:19:43) 16:51:44 INFO - javascript benchmark 16:51:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.2280315905227, 2583.6641236639234, 2595.0657763205245, 2615.3069436599294, 2621.9868900655492, 4481.238189507783, 4514.074614703986, 4622.607078398336, 4678.485366609674, 4689.5310468953085, 4552.112338094073, 4616.746078965655, 4634.745089020103, 4645.210314246578, 4732.242841145408, 4224.746515209097, 4243.32106862902, 4271.252530807112, 4309.245881970643, 4377.0, 4291.141771645668, 4345.196138714348, 4398.966242932925, 4418.911621767563, 4434.268345722952, 4304.935425968613, 4342.066455712005, 4343.239933011726, 4358.97820510897, 4379.277419225824, 4159.542450330461, 4162.6253637172495, 4189.97905010473, 4254.1279037797485, 4277.743335399886, 4189.601987811153, 4201.621854033121, 4206.116715489751, 4207.621314081747, 4232.132412855357, 4615.053913947632, 4618.9076218475775, 4642.605378542828, 4646.697964632322, 4659.091477161955, 4775.832845850413, 4901.338319326881, 4962.751862406865, 4965.0566392385335, 4973.204287314049, 5014.097462456756, 5027.195648696227, 5031.22016087507, 5047.116754567936, 5082.542571168613, 4673.439187297546, 4744.833930812405, 4747.8100875965265, 4751.501092385319, 4752.025834703877, 4953.628477864103, 4969.925451118236, 4996.375453068366, 4997.925031124536, 5088.160453525127, 4850.83022094225, 4889.217725164028, 4890.046440943982, 4890.242012487999, 4893.4617192108835, 4929.06347793918, 4934.9753251233515, 4968.8757781055765, 4974.726390048582, 4975.651704380731, 4723.102610504062, 4814.470408255089, 4855.514448555187, 4862.051899879561, 4918.065567542156, 4864.36763220779, 4876.512348765166, 4879.658423910363, 4883.682560633619, 4927.77824997876, 4118.361653943643, 4125.91748165035, 4127.050778321003, 4139.875803725893, 4166.937495937564, 3566.964330356715, 3592.1558433768046, 3592.604813470516, 3621.6378362163573, 3659.451082337671, 4319.179355922364, 4321.848735294249, 4321.978390108029, 4341.544137865541, 4358.084802191575, 4957.157283261854, 4957.182064959241, 4959.107360675041, 4961.032598643302, 4996.775145118479, 4244.915101697949, 4283.828646854154, 4334.089841133335, 4335.588119128678, 4345.739255644671, 4441.666874984389, 4461.977690111594, 4464.129494748493, 4493.483249426293, 4505.301678239878, 4719.032598317268, 4721.244600863845, 4724.976375118103, 4725.5510726480925, 4762.3332733416655, 4836.903261934812, 4919.286703428054, 4924.064427758714, 4928.236123400879, 4991.101601711727, 4699.036697477078, 4708.717476951394, 4713.410823647044, 4751.0, 4758.762061896822, 4843.539863712942, 4847.030593881308, 4850.46644869057, 4852.587530059844, 4861.44093429246, 4746.311784791117, 4900.485449027943, 4948.975255123702, 4952.306677065286, 4978.352814134139, 4776.593989510791, 4830.7826147824135, 4839.346688197189, 4872.074305881871, 4885.145099607484, 4810.158222311012, 4821.734804585782, 4831.782569784438, 4835.29888166221, 4883.511648835088, 4536.9092618148115, 4637.698549594265, 4663.626909847137, 4673.6728429011, 4742.644301677319, 4828.975855120842, 4855.951440485691, 4859.441164266154, 4885.609151267961, 4910.705357678551, 4723.929141062956, 4724.078804633064, 4729.077829823152, 4729.385179926588, 4761.928571071506], "value": 4546.485454542342, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.8611190258388, 580.2688612348439, 585.5227989188811, 585.9912101318483, 585.9941400585994, 28835.85582072089, 38929.80535097328, 40062.399064014025, 40141.38727514523, 44228.0, 40716.59283407165, 41132.177356452856, 41134.0, 41170.58829411705, 41193.176136477254, 7619.923800761977, 7749.418793590475, 7758.030246219223, 7760.456768026241, 7762.068551773795, 7359.852802943965, 9688.370255933341, 9970.052844979715, 9973.850392244121, 10334.276600637959, 9769.658061967833, 9852.211823054138, 9859.90140098597, 9861.0, 9871.259655525828, 9910.454924979123, 9969.551370188286, 9983.101520863085, 9991.950040249825, 9996.600135994551, 9565.808683826353, 9631.133198012143, 9639.373440726331, 9643.132118109404, 9643.180329671986, 38994.22011559781, 39331.213375732324, 39342.0, 39360.803195984125, 39419.802900985596, 2924.2396976785976, 3078.7690923180626, 3261.1684020574603, 3344.7324214062814, 3732.6640602345647, 28785.28036799076, 30490.84754576235, 30988.690113098804, 31996.360072798645, 32219.677803222137, 3306.0, 3427.2288735034667, 3450.9482357764655, 3470.23654795947, 3598.6041535431345, 303647.0, 309453.0, 309624.0, 428884.0, 429044.0, 58104.0, 58811.35452391162, 58827.11759323614, 58904.7054764732, 58938.705306473195, 34002.31995360079, 35262.47106293408, 35269.29461410757, 61766.69116654388, 62109.0, 13022.0, 13129.0, 13136.60590182296, 13143.67140821468, 13171.143875648053, 9812.26408019387, 9857.359271647463, 10710.08964238033, 10885.782284354269, 10890.0, 18834.905825470785, 19406.599610738933, 19435.73667711583, 19470.046655371287, 19600.313989010316, 7128.429725621938, 7194.784156475314, 7362.44781641379, 7517.849643007109, 7537.585432801248, 734.181387752652, 810.6676262732251, 922.7969846633864, 929.8651695504115, 989.7674046599044, 26509.867450662623, 28153.0, 28580.714192857806, 28610.570841437395, 28666.0, 920.4661296448044, 925.717656114878, 949.2121539122512, 961.6874515782399, 1032.0195813976752, 14570.562883113524, 14840.40638374453, 15119.395224191128, 15399.92300038515, 16030.519084427486, 398323.0, 400147.0, 400302.0, 401166.0, 401167.0, 51421.48578514242, 51710.0, 51884.48115518872, 51916.22125668118, 51984.0, 1073.4739977411168, 1085.5440714899603, 1090.8909109089027, 1145.1468655851277, 1185.105245539612, 20880.58238835245, 28355.858220708764, 28879.0, 31303.217419564695, 32010.67989320077, 45810.0, 46606.0, 47158.528414715416, 47160.52839471699, 47231.0, 332871.0, 333432.0, 333556.0, 333936.0, 334477.0, 22334.329970100924, 22383.32850014502, 22911.656325154803, 25179.370515737253, 25760.61359079578, 39717.2056558873, 40091.0, 40532.79733601313, 41067.383989240785, 41236.58763412327, 34455.0, 34695.65304347025, 36442.63557364392, 37486.2502749938, 37985.81007094947, 36743.26513469662, 36806.0, 37333.253334933695, 37494.625053749114, 37497.62502374942, 35898.8205058973, 36222.45666314955, 36280.81859590685, 36326.0, 36503.0, 31965.68034319627, 32661.0, 33710.0, 33811.15472113217, 34195.14512137217, 25237.369065773502, 25899.741002589733, 26213.606795898457, 26304.473910522065, 26682.332941675835], "value": 18223.91077537249, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.8947160587628, 360.77992424620976, 364.5716283367044, 370.5941993517098, 382.9770213787173, 16021.0, 17000.91499542502, 17209.82790172098, 17358.913205433953, 17364.0, 16268.0, 16304.51086467408, 16815.91592042041, 16861.494155175365, 17009.57476063096, 4704.506026867181, 4937.210046392578, 4939.110960027194, 4941.925871111936, 4944.60443164546, 3761.717871159674, 3904.629060239273, 4901.558859702626, 5785.450382213683, 6047.788327408542, 6051.1830902828215, 6060.484858787008, 6061.787837425691, 6063.575549711522, 6067.9696601516935, 5788.739506722187, 5875.0, 5914.0, 5919.911201331984, 5932.139839723238, 4705.482396936334, 5205.739713014372, 5207.661502002357, 5210.921836172461, 5222.190560463135, 12511.249325040526, 13999.930000349934, 19959.700604490943, 20803.5839283215, 20813.791862081336, 1819.5360183153343, 1839.062078340043, 1854.7866995295444, 2014.174188582674, 2222.155580879272, 8756.124387561256, 9112.726618201465, 9182.357234993553, 9395.248380129573, 9566.186874115709, 1617.1509957272526, 1659.1538315459081, 1695.9745603815952, 1705.360489816319, 1777.4489908128521, 69493.65253173752, 69953.65023174851, 70036.6498167511, 71958.64020679916, 72364.63817680877, 9808.46053467052, 10014.24893133018, 10084.546195421077, 10161.745956351151, 10279.126274266622, 13572.457101716018, 13586.796198057036, 13662.931685341708, 13663.0, 13668.384922678304, 6906.136732908387, 7136.536125151849, 7178.820529486805, 7188.748393806296, 7450.292222238879, 6353.428191462791, 6392.776252831129, 6566.113574667359, 6624.304448032887, 6626.734930602722, 4263.722858014281, 4329.285667864767, 4330.501992270866, 4387.2103021455805, 4434.246178149722, 1594.4499147793993, 1740.3908631978707, 1768.2308195934552, 1809.1316168239318, 1829.1768704083215, 857.446946719375, 910.7222297199418, 929.8326301265702, 933.2021121940643, 935.7473482159779, 16557.0, 16862.662746744998, 16880.493585192464, 17304.826951730574, 18075.72886406705, 813.8331642013374, 842.2630198576246, 848.8005318750089, 865.9826803463895, 913.8400779863497, 9296.39573427725, 9816.263780216512, 9846.409215446953, 9902.306838521234, 10032.1472674822, 357585.0, 362661.0, 363171.0, 363823.0, 363980.0, 74094.62952685202, 74473.25526744772, 75433.62283188548, 76660.61669691616, 76969.0, 857.8578530498172, 870.3428911172025, 897.6813231302924, 897.7710683775596, 897.7845317123844, 14895.47865824691, 14969.46723542417, 15335.313115557057, 15473.0, 15473.613159671098, 27483.725162748116, 35815.46276805798, 37058.0, 37198.44202336913, 38239.0, 48922.26616600825, 50708.492915070376, 50991.490085098674, 51273.487265126874, 51415.0, 7039.436845052486, 7056.682449289691, 7372.262773722585, 7608.0489938757655, 7810.960945195237, 10567.418791966516, 11087.611933582608, 11088.667339979813, 11148.609798657008, 11290.322580645188, 9718.368306060083, 9843.85234221473, 10091.697249082246, 10356.637517686844, 10367.377957322586, 9809.950950245204, 9908.851367229641, 10166.135878450414, 10183.0, 10317.0, 9965.850512242178, 10031.0, 10228.693139205836, 10338.6737983953, 10574.418406987383, 7109.466789990668, 7296.562206267641, 7462.738804141829, 7484.850302994018, 7495.0, 8896.332775042027, 9731.513424328898, 9898.851517227391, 9986.60053597877, 10320.741981450223], "value": 8067.773864734428, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.3178410794603, 1394.9023568350215, 1469.742795010873, 1637.0750525952844, 1676.4886709553589, 7547.6603552840115, 8118.14759450258, 8128.471649342803, 8134.105248422668, 8137.226963438471, 6778.0, 7291.8906216406795, 7463.962680186605, 7507.24927507249, 8038.758837234877, 2104.3055791588777, 2135.1672847589452, 2136.4552039229975, 2136.4552039230052, 2150.9354719358444, 2108.399106254718, 2146.720926279589, 2214.0700905619597, 2214.6235140026233, 2217.1131547381015, 2175.6410192318326, 2177.129148340661, 2180.6401943679275, 2185.846990710651, 2221.911123555064, 2079.1683326669304, 2094.361219827952, 2097.8531502794813, 2108.98945505273, 2114.217739436403, 2095.8113769760644, 2145.1205005947486, 2147.849650524464, 2149.8925053747407, 2150.236665983569, 7698.730544430918, 7766.57283849388, 8050.677972881078, 8158.347332213409, 8160.591970401516, 4369.08249267651, 4487.551244875519, 4488.438945131858, 4494.595486406239, 4496.662750293741, 2772.875220615057, 2894.52240380337, 2938.750206232473, 2946.6611339695783, 2954.9556756648667, 897.6185121323412, 943.9244860411151, 954.1794057110949, 956.7273327101742, 964.6045121500222, 10602.04581587653, 10605.681829545127, 10615.628453004108, 10624.09695175911, 10636.57453701851, 3963.365861462152, 4207.0113523322, 4221.746695198298, 4247.447831781848, 4332.718373305725, 9023.548822558845, 9045.819083618291, 9048.0, 9053.366264361432, 9064.50145242018, 4386.627136693353, 4416.514183439819, 4418.845340413134, 4424.977875110668, 4427.911441771146, 3556.8577256909434, 3965.325894597925, 4071.816768245377, 4075.3071977763852, 4119.505659320901, 3606.4770608261656, 3627.927441451209, 3746.6066063062985, 3746.8875933722034, 3752.9249415011545, 1146.7821113988316, 1166.2069792540983, 1173.976520469586, 1175.988240117605, 1197.0543270815958, 738.2433006168612, 853.807893224032, 866.9479831210148, 873.6549063120032, 900.7297810656777, 5391.56867450603, 5444.128939369682, 5551.2228288039705, 5633.042382794934, 5689.260396148474, 398.26122542683487, 411.6727201874517, 412.36495796473696, 414.6123374644717, 419.8803341047787, 3833.2333533293454, 3855.6722678572073, 3892.9805350973065, 3997.661952269887, 4025.5571887092397, 9126.08739126082, 9369.48467834262, 9392.483413412192, 9413.152816246571, 9422.528873556432, 9244.121808428188, 9355.48544830041, 9355.859662105075, 9357.90642093584, 9770.169535589555, 7147.2852714728115, 7303.123625165014, 7318.085239345082, 7331.083614548182, 7367.594782286918, 5396.9730151348995, 5609.719514024364, 5662.037453632975, 5731.426857314235, 5850.824475265749, 8795.164459376349, 9070.229030532215, 9164.404313719588, 9246.95376523113, 9267.76830579241, 8746.562671866508, 9051.0, 9118.133777291147, 9400.15398614128, 9406.623735050794, 6710.094137291599, 6866.382025617719, 6893.551919125241, 7074.221835597978, 7077.0, 7690.307872291521, 7746.573938433215, 7748.65131069092, 7753.534787912743, 7755.457118001911, 6658.800235992928, 6664.83337916556, 6665.700043498151, 6703.765368212089, 6996.335348141918, 6704.597724136567, 6781.6270105144695, 6826.522143450109, 6881.311868813079, 6974.128233970754, 6532.183477065367, 6636.7013484392355, 6636.7345306189145, 6640.0, 6643.269240383651, 4645.674802763774, 4798.640101992295, 4840.1287768200655, 4849.684770489906, 4934.481879402611, 4724.338592597108, 4814.566688998007, 4907.214845624683, 4930.4822993586595, 4942.184539550934], "value": 4091.604732475837, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.4493796600358, 302.23082255659347, 306.9248034231613, 307.5525110963548, 310.93314937288494, 4861.5935112119505, 4913.11563918495, 4919.557239848413, 4937.629677774173, 4959.479254678261, 4609.953900460994, 4647.186742320097, 4804.855854324367, 4809.759512024402, 4867.3672422585105, 3803.619638036202, 3806.29583527047, 3812.7712337259854, 3817.675497582695, 3818.579956204815, 3796.5823759386444, 3807.809609519527, 3818.8472461101524, 3834.7537050458573, 3838.539375274971, 3633.6003039665616, 3654.543182102237, 3766.3973764197735, 3771.283456143338, 3802.6387493188104, 3816.9618303816883, 3820.0, 3823.617638236168, 3824.368979118442, 3838.3282925488206, 3375.29118885032, 3384.120128766514, 3386.5258863759095, 3388.8813891513923, 3413.3173365327034, 4575.382323386333, 4754.334393184957, 4759.547842954914, 4796.400449943757, 4820.373351464323, 4284.335927931175, 4289.2922667759785, 4292.656587472995, 4295.797176790504, 4334.609885110323, 5057.443681195065, 5061.848144555669, 5091.61812864033, 5113.51421614946, 5153.896922061538, 3593.8921832345072, 3600.513930619359, 3617.276544691072, 3624.6012938576737, 3635.745497815154, 5151.330327057459, 5256.632035757537, 5305.575553955713, 5321.175217841241, 5321.361436627591, 3640.199156185635, 3651.561812582507, 3660.3228402745044, 3667.8166091695307, 3675.831259556874, 3728.3475391806323, 3730.4963829883163, 3732.64539868712, 3737.271232109767, 3747.2318174774105, 4144.544100148981, 4239.689369293742, 4312.310030395121, 4313.805878735402, 4326.199653064194, 4996.326965073741, 5017.498250174954, 5024.145895197825, 5044.445111037783, 5046.34397528319, 1215.9331236782061, 1268.460904115747, 1284.7045179608742, 1285.7592423311362, 1294.9223046617233, 860.9496414374453, 874.9387542871938, 881.1232823340816, 884.9778755531163, 886.4636894678755, 4436.933445998313, 4472.373867658531, 4476.216662084123, 4480.283154695233, 4507.5350511084025, 5173.405058418255, 5181.870453238624, 5188.8962220755375, 5200.959808038408, 5206.453322401093, 4378.277584198571, 4486.3494793254795, 4505.279155335196, 4523.683342166082, 4536.841210557614, 2743.1633351827486, 2753.931151721183, 2773.1680495851165, 2773.611694362791, 2774.5838124281518, 4920.138975679313, 4929.482404347492, 4929.531694489017, 4936.925946110811, 4944.802207911644, 5114.107780121304, 5324.565655152483, 5352.759125839347, 5376.91934620981, 5379.892402151935, 4958.752062396866, 4965.453800081988, 4973.875653108701, 4974.776135073858, 5005.849824505198, 5299.788008479618, 5311.760970756248, 5313.2561441397465, 5330.0, 5356.723392558225, 4889.413270407574, 5099.69401835891, 5155.200943853709, 5167.509086636764, 5170.1986192140275, 4971.776270067783, 4994.9750251248515, 5010.273510341053, 5012.248162775612, 5025.522575355335, 3799.316123097869, 3818.4081467372603, 3818.7517811342173, 3832.5400951885954, 3840.4431357453573, 5074.695518268915, 5208.192730126836, 5208.8437346879655, 5213.582913366846, 5228.0, 5028.622853285945, 5032.245163225545, 5035.615205818429, 5037.471065538212, 5081.923771143362, 5027.930211383772, 5053.898922021613, 5063.13926632466, 5066.316047333563, 5093.752030752489, 4709.317149013443, 4857.441394239567, 4920.188168952082, 4920.827771027997, 4934.138199844154, 4969.35398398206, 5102.158143906214, 5112.130937740668, 5125.3353851996, 5149.639525233198, 4986.626003049713, 5002.549770520671, 5012.598992080698, 5024.447310795883, 5030.9245361320345], "value": 3835.127480700582, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.2782230878718, 649.3928177154364, 658.8319978405506, 700.982475438114, 713.9647511108893, 11829.940850295747, 12698.746025079492, 12827.16623419477, 12850.357482125892, 12854.742905141891, 12330.63008109758, 12457.314847683374, 12556.811647825267, 12572.0, 12584.433700483478, 609.2506217352659, 744.0773440933258, 749.7450866705319, 768.1435199752292, 770.4722265248323, 768.2816566510303, 769.5728870476883, 770.7649167004062, 771.178694690156, 771.9266669666373, 799.7280924485674, 803.2569872867574, 803.3091541274499, 804.3364224514769, 805.0701439836969, 648.1153225846709, 773.9071311442607, 796.8844517544982, 796.9083555391118, 801.2988634944423, 583.9416058394169, 587.4213899309219, 588.3087372337529, 588.3116753398534, 588.9676067816268, 11848.17062805604, 11857.644270671894, 11873.703157421047, 11876.287422754576, 11896.881031189663, 11404.315741055563, 11578.884211157865, 11694.55837300047, 11758.470868810839, 12915.87084129156, 10183.89816101837, 10276.12268564803, 11550.480228389742, 11824.23142495735, 11993.220440671328, 3116.2520994961274, 3195.760317976161, 3229.3864165808423, 3267.477203647405, 3412.4540073588355, 60704.39295607032, 62208.0, 63284.36715632877, 63384.68307658432, 64525.3547464524, 9532.142107210384, 9765.169960553432, 10074.244431667508, 10096.14182794456, 10249.12882404989, 18935.0, 19244.23023079089, 19265.8073419264, 19282.13230404635, 19354.032298385024, 6116.424843293477, 6333.398327158912, 6374.330695277022, 6379.55343125986, 6384.361563843671, 3638.9818050909576, 3704.2961837250837, 3780.9243815123546, 3783.375743002429, 3792.1088544192094, 2953.5126704094014, 3056.449839028996, 3077.199928018731, 3081.245094951751, 3087.768417368706, 475.2064053031446, 494.82433736023916, 500.3345550417935, 511.307178772765, 529.0292313604579, 444.96885218034424, 497.42547357801794, 512.9333186685782, 520.687587447536, 553.1536748774382, 7618.657160427794, 7784.571848548384, 7841.255080767318, 8213.178682131738, 8213.44260834708, 535.6625326044567, 539.8920215956824, 547.2366049361199, 550.5678042736432, 557.5623135838348, 3657.6708096271464, 3679.816009199529, 3691.667749902522, 3797.3164830330807, 3814.3324918139215, 105447.47276263569, 105794.47102764437, 107606.0, 108829.0, 110108.0, 70983.2901670987, 74344.62827685828, 82203.0, 82210.17789822145, 83648.0, 63322.0, 70499.0, 71110.28889711243, 72535.637321812, 74242.0, 8456.412688112956, 8768.473891566526, 8806.929587252787, 8843.248323892542, 9086.591103400231, 47550.76224618855, 48257.51742482671, 49058.509414906825, 49274.0, 49335.259971099746, 61478.69260653847, 68510.65744671444, 69533.0, 69561.0, 70238.64880675563, 10564.207684423545, 10705.250632455653, 11168.21822472419, 11645.0, 12183.878161218629, 11659.475323610628, 11679.591214307458, 11766.572777403117, 11931.761364772483, 12285.262884226975, 8648.351373646876, 9962.651307204213, 10102.584858614979, 11351.432428378714, 11354.772904542027, 11582.536698531965, 12032.27806331623, 12263.632091037283, 12538.87461125377, 12634.431450584929, 10632.84050739255, 10644.308119972176, 10919.83620245681, 10958.45207739594, 11274.605388811353, 8768.167024132697, 8856.158664926821, 9040.230975953315, 9108.77228069277, 9409.29430292717, 9027.45835249887, 9520.143187113194, 9737.561809718722, 9819.803603928025, 9820.361676491291], "value": 6402.16566200822, "unit": "score"}], "name": "dromaeo_css", "value": 6375.592158708191, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 114.0, 116.0, 110.0, 117.0, 116.0, 114.0, 115.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 125.0, 190.0, 124.0, 122.0, 152.0, 123.0, 191.0, 128.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 104.0, 76.0, 78.0, 77.0, 76.0, 102.0, 77.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 144.0, 88.0, 86.0, 87.0, 116.0, 86.0, 87.0, 123.0], "value": 99.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 65.0, 66.0, 67.0, 65.0, 66.0, 66.0, 64.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 83.0, 83.0, 83.0, 82.0, 81.0, 82.0, 83.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 129.0, 134.0, 131.0, 129.0, 133.0, 140.0, 149.0, 135.0], "value": 135.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 169.0, 172.0, 170.0, 172.0, 170.0, 170.0, 170.0, 169.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 67.0, 70.0, 69.0, 67.0, 69.0, 67.0, 67.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1543.8999999999999, "alertThreshold": 2.0}]} 16:51:44 INFO - Return code: 0 16:51:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:51:44 INFO - # TBPL SUCCESS # 16:51:44 INFO - Running post-action listener: _resource_record_post_action 16:51:44 INFO - [mozharness: 2016-08-18 23:51:44.401469Z] Finished run-tests step (success) 16:51:44 INFO - Running post-run listener: _resource_record_post_run 16:51:44 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396812288; Read time: 4; Write time: 271672 16:51:44 INFO - TinderboxPrint: CPU usage
13.0% 16:51:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 16:51:44 INFO - TinderboxPrint: I/O write bytes / time
396,812,288 / 271,672 16:51:44 INFO - TinderboxPrint: CPU idle
8,336.6 (87.0%) 16:51:44 INFO - TinderboxPrint: CPU user
1,200.6 (12.5%) 16:51:44 INFO - TinderboxPrint: Swap in / out
0 / 0 16:51:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114135040; Read time: 0; Write time: 98980 16:51:44 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 282677248; Read time: 0; Write time: 172692 16:51:45 INFO - Running post-run listener: _upload_blobber_files 16:51:45 INFO - Blob upload gear active. 16:51:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:51:45 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 16:51:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:51:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:51:45 INFO - (blobuploader) - INFO - Open directory for files ... 16:51:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:51:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:51:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:46 INFO - (blobuploader) - INFO - Done attempting. 16:51:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:51:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:49 INFO - (blobuploader) - INFO - Done attempting. 16:51:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:51:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:50 INFO - (blobuploader) - INFO - Done attempting. 16:51:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:51:50 INFO - Return code: 0 16:51:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:51:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:51:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ad81e912489d02d3f61e01eb6c6e55669968a31242257bb9f538233c30a80cf48a5bfe1a0f82bf16b157635ea25329cdee678905f86e75363b7025d7aab75cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c625b54b7ad76a508da36aa7923df5391a5f671b755643fcc0e8d6ea7a351c0a5a3dd0c312817f448cbbf4a3203c50b612940db4064fcc0fc9090dd3eb130f6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0b8b351caa2ee678cd6fb44e265641f5bcb8bf76c7e854ff591cadf09ed7a999cfbccd34a51db0044591cf1667b276ad3d522e8c472861ab72e08a52f4a69b9"} 16:51:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:51:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:51:50 INFO - Contents: 16:51:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ad81e912489d02d3f61e01eb6c6e55669968a31242257bb9f538233c30a80cf48a5bfe1a0f82bf16b157635ea25329cdee678905f86e75363b7025d7aab75cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c625b54b7ad76a508da36aa7923df5391a5f671b755643fcc0e8d6ea7a351c0a5a3dd0c312817f448cbbf4a3203c50b612940db4064fcc0fc9090dd3eb130f6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0b8b351caa2ee678cd6fb44e265641f5bcb8bf76c7e854ff591cadf09ed7a999cfbccd34a51db0044591cf1667b276ad3d522e8c472861ab72e08a52f4a69b9"} 16:51:50 INFO - Running post-run listener: copy_logs_to_upload_dir 16:51:50 INFO - Copying logs to upload dir... 16:51:50 INFO - mkdir: /builds/slave/test/build/upload/logs 16:51:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.091413 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-18 16:51:50.353658) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 16:51:50.357057) ========= 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=923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ad81e912489d02d3f61e01eb6c6e55669968a31242257bb9f538233c30a80cf48a5bfe1a0f82bf16b157635ea25329cdee678905f86e75363b7025d7aab75cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c625b54b7ad76a508da36aa7923df5391a5f671b755643fcc0e8d6ea7a351c0a5a3dd0c312817f448cbbf4a3203c50b612940db4064fcc0fc9090dd3eb130f6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0b8b351caa2ee678cd6fb44e265641f5bcb8bf76c7e854ff591cadf09ed7a999cfbccd34a51db0044591cf1667b276ad3d522e8c472861ab72e08a52f4a69b9"} build_url:https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/IRBMonxTSTSV10cLMpLJGg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ad81e912489d02d3f61e01eb6c6e55669968a31242257bb9f538233c30a80cf48a5bfe1a0f82bf16b157635ea25329cdee678905f86e75363b7025d7aab75cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c625b54b7ad76a508da36aa7923df5391a5f671b755643fcc0e8d6ea7a351c0a5a3dd0c312817f448cbbf4a3203c50b612940db4064fcc0fc9090dd3eb130f6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0b8b351caa2ee678cd6fb44e265641f5bcb8bf76c7e854ff591cadf09ed7a999cfbccd34a51db0044591cf1667b276ad3d522e8c472861ab72e08a52f4a69b9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 16:51:50.382265) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:51:50.382571) ========= 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=923d835db2ba3686ebe126b700000192-1471563064.444244-1907175387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:51:50.468425) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 16:51:50.468744) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 16:51:50.469028) ========= ========= Total master_lag: 0.16 =========