builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-013 starttime: 1472795389.65 results: success (0) buildid: 20160901215642 builduid: ad10029f6041452ebb85c5573b0fc16b revision: 4c4ce191fc9b444f1f47272293e001707538c105 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.652315) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.652850) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.653166) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.668048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.668378) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 22:49:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.7M=0s 2016-09-01 22:49:49 (38.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085142 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.767522) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.767878) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020760 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.817626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:49.818055) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4c4ce191fc9b444f1f47272293e001707538c105 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4c4ce191fc9b444f1f47272293e001707538c105 --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=9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 22:49:50,098 truncating revision to first 12 chars 2016-09-01 22:49:50,098 Setting DEBUG logging. 2016-09-01 22:49:50,098 attempt 1/10 2016-09-01 22:49:50,098 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4c4ce191fc9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 22:49:50,659 unpacking tar archive at: fx-team-4c4ce191fc9b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.948422 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:50.780336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:50.780715) ========= script_repo_revision: 4c4ce191fc9b444f1f47272293e001707538c105 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:50.781151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:50.781461) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 22:49:50.792275) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-09-01 22:49:50.792600) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:49:51 INFO - MultiFileLogger online at 20160901 22:49:51 in /builds/slave/test 22:49:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 22:49:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:49:51 INFO - {'append_to_log': False, 22:49:51 INFO - 'base_work_dir': '/builds/slave/test', 22:49:51 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:49:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:49:51 INFO - 'branch': 'Fx-Team-Non-PGO', 22:49:51 INFO - 'buildbot_json_path': 'buildprops.json', 22:49:51 INFO - 'config_files': ('talos/linux_config.py',), 22:49:51 INFO - 'default_actions': ('clobber', 22:49:51 INFO - 'read-buildbot-config', 22:49:51 INFO - 'download-and-extract', 22:49:51 INFO - 'populate-webroot', 22:49:51 INFO - 'create-virtualenv', 22:49:51 INFO - 'install', 22:49:51 INFO - 'run-tests'), 22:49:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:49:51 INFO - 'download_minidump_stackwalk': True, 22:49:51 INFO - 'download_symbols': 'ondemand', 22:49:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:49:51 INFO - 'tooltool.py': '/tools/tooltool.py', 22:49:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:49:51 INFO - '/tools/misc-python/virtualenv.py')}, 22:49:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:49:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:49:51 INFO - 'installer_path': 'installer.exe', 22:49:51 INFO - 'log_level': 'info', 22:49:51 INFO - 'log_name': 'talos', 22:49:51 INFO - 'log_to_console': True, 22:49:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:49:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:49:51 INFO - 'opt_config_files': (), 22:49:51 INFO - 'pip_index': False, 22:49:51 INFO - 'sps_profile': False, 22:49:51 INFO - 'sps_profile_interval': 0, 22:49:51 INFO - 'suite': 'dromaeojs', 22:49:51 INFO - 'system_bits': '32', 22:49:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:49:51 INFO - 'title': 'talos-linux64-ix-013', 22:49:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:49:51 INFO - 'use_talos_json': True, 22:49:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:49:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:49:51 INFO - 'work_dir': 'build'} 22:49:51 INFO - [mozharness: 2016-09-02 05:49:51.167754Z] Running clobber step. 22:49:51 INFO - Running pre-action listener: _resource_record_pre_action 22:49:51 INFO - Running main action method: clobber 22:49:51 INFO - rmtree: /builds/slave/test/build 22:49:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:49:51 INFO - Running post-action listener: _resource_record_post_action 22:49:51 INFO - [mozharness: 2016-09-02 05:49:51.616613Z] Finished clobber step (success) 22:49:51 INFO - [mozharness: 2016-09-02 05:49:51.616715Z] Running read-buildbot-config step. 22:49:51 INFO - Running pre-action listener: _resource_record_pre_action 22:49:51 INFO - Running main action method: read_buildbot_config 22:49:51 INFO - Using buildbot properties: 22:49:51 INFO - { 22:49:51 INFO - "project": "", 22:49:51 INFO - "product": "firefox", 22:49:51 INFO - "script_repo_revision": "production", 22:49:51 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:49:51 INFO - "repository": "", 22:49:51 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:49:51 INFO - "buildid": "20160901215642", 22:49:51 INFO - "pgo_build": "False", 22:49:51 INFO - "basedir": "/builds/slave/test", 22:49:51 INFO - "buildnumber": 3267, 22:49:51 INFO - "slavename": "talos-linux64-ix-013", 22:49:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:49:51 INFO - "platform": "ubuntu64_hw", 22:49:51 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:49:51 INFO - "branch": "fx-team", 22:49:51 INFO - "repo_path": "integration/fx-team", 22:49:51 INFO - "revision": "4c4ce191fc9b444f1f47272293e001707538c105", 22:49:51 INFO - "stage_platform": "linux64", 22:49:51 INFO - "builduid": "ad10029f6041452ebb85c5573b0fc16b", 22:49:51 INFO - "slavebuilddir": "test" 22:49:51 INFO - } 22:49:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:49:51 INFO - Running post-action listener: _resource_record_post_action 22:49:51 INFO - [mozharness: 2016-09-02 05:49:51.619146Z] Finished read-buildbot-config step (success) 22:49:51 INFO - [mozharness: 2016-09-02 05:49:51.619235Z] Running download-and-extract step. 22:49:51 INFO - Running pre-action listener: _resource_record_pre_action 22:49:51 INFO - Running main action method: download_and_extract 22:49:51 INFO - mkdir: /builds/slave/test/build/tests 22:49:51 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')]} 22:49:51 INFO - trying https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:49:51 INFO - Downloading https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:49:52 INFO - Downloaded 1361 bytes. 22:49:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:49:52 INFO - Using the following test package requirements: 22:49:52 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:49:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:49:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'jsshell-linux-x86_64.zip'], 22:49:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:49:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:49:52 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:49:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:49:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:49:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:49:52 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')]} 22:49:52 INFO - trying https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:49:52 INFO - Downloading https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:49:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:49:53 INFO - Downloaded 20334556 bytes. 22:49:53 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 22:49:57 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 22:49:57 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')]} 22:49:57 INFO - trying https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:49:57 INFO - Downloading https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:49:58 INFO - Downloaded 20334556 bytes. 22:49:58 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 22:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:02 INFO - trying https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:50:02 INFO - Downloading https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:50:03 INFO - Downloaded 14413998 bytes. 22:50:03 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 22:50:03 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')]} 22:50:03 INFO - trying https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:50:03 INFO - Downloading https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:50:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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 22:50:04 INFO - Downloaded 55868044 bytes. 22:50:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:50:04 INFO - mkdir: /builds/slave/test/properties 22:50:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:50:04 INFO - Writing to file /builds/slave/test/properties/build_url 22:50:04 INFO - Contents: 22:50:04 INFO - build_url:https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:50:05 INFO - Running post-action listener: _resource_record_post_action 22:50:05 INFO - Running post-action listener: set_extra_try_arguments 22:50:05 INFO - [mozharness: 2016-09-02 05:50:05.517752Z] Finished download-and-extract step (success) 22:50:05 INFO - [mozharness: 2016-09-02 05:50:05.517978Z] Running populate-webroot step. 22:50:05 INFO - Running pre-action listener: _resource_record_pre_action 22:50:05 INFO - Running main action method: populate_webroot 22:50:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:50:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:50:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:50:05 INFO - '--tppagecycles', 22:50:05 INFO - '7'], 22:50:05 INFO - 'tests': ['tsvgm']}}, 22:50:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:50:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:50:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:50:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:50:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:50:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:50:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['damp', 'tps']}, 22:50:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'tests': ['damp', 'tps']}, 22:50:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['dromaeo_dom']}, 22:50:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:50:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['basic_compositor_video']}, 22:50:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:50:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['a11yr', 22:50:05 INFO - 'ts_paint', 22:50:05 INFO - 'tpaint', 22:50:05 INFO - 'sessionrestore', 22:50:05 INFO - 'sessionrestore_no_auto_restore', 22:50:05 INFO - 'tabpaint']}, 22:50:05 INFO - 'other-e10s': {'tests': ['a11yr', 22:50:05 INFO - 'ts_paint', 22:50:05 INFO - 'tpaint', 22:50:05 INFO - 'sessionrestore', 22:50:05 INFO - 'sessionrestore_no_auto_restore', 22:50:05 INFO - 'tabpaint']}, 22:50:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:50:05 INFO - 'ts_paint', 22:50:05 INFO - 'tpaint', 22:50:05 INFO - 'sessionrestore', 22:50:05 INFO - 'sessionrestore_no_auto_restore', 22:50:05 INFO - 'tabpaint']}, 22:50:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:50:05 INFO - 'ts_paint', 22:50:05 INFO - 'tpaint', 22:50:05 INFO - 'sessionrestore', 22:50:05 INFO - 'sessionrestore_no_auto_restore', 22:50:05 INFO - 'tabpaint']}, 22:50:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['a11yr', 22:50:05 INFO - 'ts_paint', 22:50:05 INFO - 'tpaint', 22:50:05 INFO - 'sessionrestore', 22:50:05 INFO - 'sessionrestore_no_auto_restore', 22:50:05 INFO - 'tabpaint']}, 22:50:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['a11yr', 22:50:05 INFO - 'ts_paint', 22:50:05 INFO - 'tpaint', 22:50:05 INFO - 'sessionrestore', 22:50:05 INFO - 'sessionrestore_no_auto_restore', 22:50:05 INFO - 'tabpaint']}, 22:50:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['tsvgx', 22:50:05 INFO - 'tsvgr_opacity', 22:50:05 INFO - 'tart', 22:50:05 INFO - 'tscrollx', 22:50:05 INFO - 'cart']}, 22:50:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:50:05 INFO - 'tsvgr_opacity', 22:50:05 INFO - 'tart', 22:50:05 INFO - 'tscrollx', 22:50:05 INFO - 'cart']}, 22:50:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'talos_options': ['--disable-e10s'], 22:50:05 INFO - 'tests': ['tp5o']}, 22:50:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'tests': ['tp5o']}, 22:50:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'talos_options': ['--disable-e10s', 22:50:05 INFO - '--xperf_path', 22:50:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:50:05 INFO - 'tests': ['tp5n']}, 22:50:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:05 INFO - 'talos_options': ['--xperf_path', 22:50:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:50:05 INFO - 'tests': ['tp5n']}}, 22:50:05 INFO - 'talos.zip': {'path': '', 22:50:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:50:05 INFO - Running post-action listener: _resource_record_post_action 22:50:05 INFO - [mozharness: 2016-09-02 05:50:05.539123Z] Finished populate-webroot step (success) 22:50:05 INFO - [mozharness: 2016-09-02 05:50:05.539213Z] Running create-virtualenv step. 22:50:05 INFO - Running pre-action listener: _resource_record_pre_action 22:50:05 INFO - Running main action method: create_virtualenv 22:50:05 INFO - Creating virtualenv /builds/slave/test/build/venv 22:50:05 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 22:50:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:50:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:50:05 INFO - Using real prefix '/usr' 22:50:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:50:06 INFO - Installing distribute.............................................................................................................................................................................................done. 22:50:08 INFO - Installing pip.................done. 22:50:08 INFO - Return code: 0 22:50:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:50: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')]} 22:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50: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')]} 22:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:08 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:08 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 22:50:08 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 22:50:08 INFO - Using env: {'DISPLAY': ':0', 22:50:08 INFO - 'HOME': '/home/cltbld', 22:50:08 INFO - 'LANG': 'en_US.UTF-8', 22:50:08 INFO - 'LANGUAGE': 'en_US:en', 22:50:08 INFO - 'LOGNAME': 'cltbld', 22:50:08 INFO - 'MAIL': '/var/mail/cltbld', 22:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:08 INFO - 'MOZ_NO_REMOTE': '1', 22:50:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:08 INFO - 'NO_EM_RESTART': '1', 22:50:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:08 INFO - 'PWD': '/builds/slave/test', 22:50:08 INFO - 'SHELL': '/bin/bash', 22:50:08 INFO - 'SHLVL': '1', 22:50:08 INFO - 'TERM': 'linux', 22:50:08 INFO - 'TMOUT': '86400', 22:50:08 INFO - 'USER': 'cltbld', 22:50:08 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:08 INFO - '_': '/tools/buildbot/bin/python'} 22:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:08 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 22:50:08 INFO - Cleaning up... 22:50:08 INFO - Return code: 0 22:50:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:50: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')]} 22:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50: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')]} 22:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:08 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:08 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 22:50:08 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 22:50:08 INFO - Using env: {'DISPLAY': ':0', 22:50:08 INFO - 'HOME': '/home/cltbld', 22:50:08 INFO - 'LANG': 'en_US.UTF-8', 22:50:08 INFO - 'LANGUAGE': 'en_US:en', 22:50:08 INFO - 'LOGNAME': 'cltbld', 22:50:08 INFO - 'MAIL': '/var/mail/cltbld', 22:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:08 INFO - 'MOZ_NO_REMOTE': '1', 22:50:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:08 INFO - 'NO_EM_RESTART': '1', 22:50:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:08 INFO - 'PWD': '/builds/slave/test', 22:50:08 INFO - 'SHELL': '/bin/bash', 22:50:08 INFO - 'SHLVL': '1', 22:50:08 INFO - 'TERM': 'linux', 22:50:08 INFO - 'TMOUT': '86400', 22:50:08 INFO - 'USER': 'cltbld', 22:50:08 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:08 INFO - '_': '/tools/buildbot/bin/python'} 22:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:08 INFO - Downloading/unpacking psutil>=3.1.1 22:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:08 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 22:50:08 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 22:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:50:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:50:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:50:10 INFO - Installing collected packages: psutil 22:50:10 INFO - Running setup.py install for psutil 22:50:10 INFO - building 'psutil._psutil_linux' extension 22:50:10 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 22:50:10 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 22:50:10 INFO - building 'psutil._psutil_posix' extension 22:50:10 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 22:50:10 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 22:50:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:50:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:50:11 INFO - Successfully installed psutil 22:50:11 INFO - Cleaning up... 22:50:11 INFO - Return code: 0 22:50:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:50:11 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')]} 22:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:11 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')]} 22:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:11 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 22:50:11 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 22:50:11 INFO - Using env: {'DISPLAY': ':0', 22:50:11 INFO - 'HOME': '/home/cltbld', 22:50:11 INFO - 'LANG': 'en_US.UTF-8', 22:50:11 INFO - 'LANGUAGE': 'en_US:en', 22:50:11 INFO - 'LOGNAME': 'cltbld', 22:50:11 INFO - 'MAIL': '/var/mail/cltbld', 22:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:11 INFO - 'MOZ_NO_REMOTE': '1', 22:50:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:11 INFO - 'NO_EM_RESTART': '1', 22:50:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:11 INFO - 'PWD': '/builds/slave/test', 22:50:11 INFO - 'SHELL': '/bin/bash', 22:50:11 INFO - 'SHLVL': '1', 22:50:11 INFO - 'TERM': 'linux', 22:50:11 INFO - 'TMOUT': '86400', 22:50:11 INFO - 'USER': 'cltbld', 22:50:11 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:11 INFO - '_': '/tools/buildbot/bin/python'} 22:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:50:11 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 22:50:11 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 22:50:11 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 22:50:11 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 22:50:11 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 22:50:11 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 22:50:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:50:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:50:13 INFO - Installing collected packages: mozsystemmonitor 22:50:13 INFO - Running setup.py install for mozsystemmonitor 22:50:13 INFO - Successfully installed mozsystemmonitor 22:50:13 INFO - Cleaning up... 22:50:13 INFO - Return code: 0 22:50:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:50:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:50:13 INFO - Using env: {'DISPLAY': ':0', 22:50:13 INFO - 'HOME': '/home/cltbld', 22:50:13 INFO - 'LANG': 'en_US.UTF-8', 22:50:13 INFO - 'LANGUAGE': 'en_US:en', 22:50:13 INFO - 'LOGNAME': 'cltbld', 22:50:13 INFO - 'MAIL': '/var/mail/cltbld', 22:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:13 INFO - 'MOZ_NO_REMOTE': '1', 22:50:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:13 INFO - 'NO_EM_RESTART': '1', 22:50:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:13 INFO - 'PWD': '/builds/slave/test', 22:50:13 INFO - 'SHELL': '/bin/bash', 22:50:13 INFO - 'SHLVL': '1', 22:50:13 INFO - 'TERM': 'linux', 22:50:13 INFO - 'TMOUT': '86400', 22:50:13 INFO - 'USER': 'cltbld', 22:50:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:13 INFO - '_': '/tools/buildbot/bin/python'} 22:50:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:13 INFO - Downloading/unpacking jsonschema==2.5.1 22:50:13 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 22:50:13 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 22:50:13 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 22:50:13 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 22:50:13 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 22:50:13 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 22:50:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:50:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:50:15 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 22:50:15 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 22:50:15 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 22:50:15 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 22:50:15 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 22:50:15 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 22:50:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:50:15 INFO - warning: no files found matching '*.txt' 22:50:15 INFO - no previously-included directories found matching 'build' 22:50:15 INFO - no previously-included directories found matching 'dist' 22:50:15 INFO - no previously-included directories found matching '.git*' 22:50:15 INFO - Installing collected packages: jsonschema, functools32 22:50:15 INFO - Running setup.py install for functools32 22:50:15 INFO - warning: no files found matching '*.txt' 22:50:15 INFO - no previously-included directories found matching 'build' 22:50:15 INFO - no previously-included directories found matching 'dist' 22:50:15 INFO - no previously-included directories found matching '.git*' 22:50:15 INFO - Successfully installed jsonschema functools32 22:50:15 INFO - Cleaning up... 22:50:15 INFO - Return code: 0 22:50:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:50:15 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')]} 22:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:15 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')]} 22:50:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:15 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:15 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 22:50:15 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 22:50:15 INFO - Using env: {'DISPLAY': ':0', 22:50:15 INFO - 'HOME': '/home/cltbld', 22:50:15 INFO - 'LANG': 'en_US.UTF-8', 22:50:15 INFO - 'LANGUAGE': 'en_US:en', 22:50:15 INFO - 'LOGNAME': 'cltbld', 22:50:15 INFO - 'MAIL': '/var/mail/cltbld', 22:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:15 INFO - 'MOZ_NO_REMOTE': '1', 22:50:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:15 INFO - 'NO_EM_RESTART': '1', 22:50:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:15 INFO - 'PWD': '/builds/slave/test', 22:50:15 INFO - 'SHELL': '/bin/bash', 22:50:15 INFO - 'SHLVL': '1', 22:50:15 INFO - 'TERM': 'linux', 22:50:15 INFO - 'TMOUT': '86400', 22:50:15 INFO - 'USER': 'cltbld', 22:50:15 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:15 INFO - '_': '/tools/buildbot/bin/python'} 22:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:50:16 INFO - Cleaning up... 22:50:16 INFO - Return code: 0 22:50:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:16 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:16 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 22:50:16 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 22:50:16 INFO - Using env: {'DISPLAY': ':0', 22:50:16 INFO - 'HOME': '/home/cltbld', 22:50:16 INFO - 'LANG': 'en_US.UTF-8', 22:50:16 INFO - 'LANGUAGE': 'en_US:en', 22:50:16 INFO - 'LOGNAME': 'cltbld', 22:50:16 INFO - 'MAIL': '/var/mail/cltbld', 22:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:16 INFO - 'MOZ_NO_REMOTE': '1', 22:50:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:16 INFO - 'NO_EM_RESTART': '1', 22:50:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:16 INFO - 'PWD': '/builds/slave/test', 22:50:16 INFO - 'SHELL': '/bin/bash', 22:50:16 INFO - 'SHLVL': '1', 22:50:16 INFO - 'TERM': 'linux', 22:50:16 INFO - 'TMOUT': '86400', 22:50:16 INFO - 'USER': 'cltbld', 22:50:16 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:16 INFO - '_': '/tools/buildbot/bin/python'} 22:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:16 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 22:50:16 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 22:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:50:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:50:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:18 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 22:50:18 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 22:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:50:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:18 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 22:50:18 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 22:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:18 INFO - Downloading docopt-0.6.1.tar.gz 22:50:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:50:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:50:19 INFO - Running setup.py install for blobuploader 22:50:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:50:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:50:19 INFO - Running setup.py install for requests 22:50:19 INFO - Running setup.py install for docopt 22:50:19 INFO - Successfully installed blobuploader requests docopt 22:50:19 INFO - Cleaning up... 22:50:19 INFO - Return code: 0 22:50:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:50: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')]} 22:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50: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')]} 22:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:19 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:19 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 22:50:19 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 22:50:19 INFO - Using env: {'DISPLAY': ':0', 22:50:19 INFO - 'HOME': '/home/cltbld', 22:50:19 INFO - 'LANG': 'en_US.UTF-8', 22:50:19 INFO - 'LANGUAGE': 'en_US:en', 22:50:19 INFO - 'LOGNAME': 'cltbld', 22:50:19 INFO - 'MAIL': '/var/mail/cltbld', 22:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:19 INFO - 'MOZ_NO_REMOTE': '1', 22:50:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:19 INFO - 'NO_EM_RESTART': '1', 22:50:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:19 INFO - 'PWD': '/builds/slave/test', 22:50:19 INFO - 'SHELL': '/bin/bash', 22:50:19 INFO - 'SHLVL': '1', 22:50:19 INFO - 'TERM': 'linux', 22:50:19 INFO - 'TMOUT': '86400', 22:50:19 INFO - 'USER': 'cltbld', 22:50:19 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:19 INFO - '_': '/tools/buildbot/bin/python'} 22:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:50:19 INFO - Running setup.py (path:/tmp/pip-SXG21N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:50:19 INFO - Running setup.py (path:/tmp/pip-zCgY6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:50:19 INFO - Running setup.py (path:/tmp/pip-xLphAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:50:20 INFO - Running setup.py (path:/tmp/pip-UdLYHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:50:20 INFO - Running setup.py (path:/tmp/pip-2n7Brx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:50:20 INFO - Running setup.py (path:/tmp/pip-JLlaS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:50:20 INFO - Running setup.py (path:/tmp/pip-PyLFy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:50:20 INFO - Running setup.py (path:/tmp/pip-7njfrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:50:20 INFO - Running setup.py (path:/tmp/pip-qguMnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:50:20 INFO - Running setup.py (path:/tmp/pip-Pm8TMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:50:20 INFO - Running setup.py (path:/tmp/pip-xTnZbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:50:20 INFO - Running setup.py (path:/tmp/pip-kjeXvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:50:20 INFO - Running setup.py (path:/tmp/pip-vGG4h9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:50:20 INFO - Running setup.py (path:/tmp/pip-ZwEGbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:50:20 INFO - Running setup.py (path:/tmp/pip-aV9J7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:50:20 INFO - Running setup.py (path:/tmp/pip-u2JG6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:50:20 INFO - Running setup.py (path:/tmp/pip-3Sp00s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:50:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:50:21 INFO - Running setup.py install for manifestparser 22:50:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Running setup.py install for mozcrash 22:50:21 INFO - Running setup.py install for mozdebug 22:50:21 INFO - Running setup.py install for mozdevice 22:50:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Running setup.py install for mozfile 22:50:21 INFO - Running setup.py install for mozhttpd 22:50:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Running setup.py install for mozinfo 22:50:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Running setup.py install for mozInstall 22:50:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:50:21 INFO - Running setup.py install for mozleak 22:50:21 INFO - Running setup.py install for mozlog 22:50:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Running setup.py install for moznetwork 22:50:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Running setup.py install for mozprocess 22:50:22 INFO - Running setup.py install for mozprofile 22:50:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Running setup.py install for mozrunner 22:50:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Running setup.py install for mozscreenshot 22:50:22 INFO - Running setup.py install for moztest 22:50:22 INFO - Running setup.py install for mozversion 22:50:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:50:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:50:22 INFO - Cleaning up... 22:50:22 INFO - Return code: 0 22:50:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:22 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:22 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 22:50:22 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 22:50:22 INFO - Using env: {'DISPLAY': ':0', 22:50:22 INFO - 'HOME': '/home/cltbld', 22:50:22 INFO - 'LANG': 'en_US.UTF-8', 22:50:22 INFO - 'LANGUAGE': 'en_US:en', 22:50:22 INFO - 'LOGNAME': 'cltbld', 22:50:22 INFO - 'MAIL': '/var/mail/cltbld', 22:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:22 INFO - 'MOZ_NO_REMOTE': '1', 22:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:22 INFO - 'NO_EM_RESTART': '1', 22:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:22 INFO - 'PWD': '/builds/slave/test', 22:50:22 INFO - 'SHELL': '/bin/bash', 22:50:22 INFO - 'SHLVL': '1', 22:50:22 INFO - 'TERM': 'linux', 22:50:22 INFO - 'TMOUT': '86400', 22:50:22 INFO - 'USER': 'cltbld', 22:50:22 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:22 INFO - '_': '/tools/buildbot/bin/python'} 22:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:50:23 INFO - Running setup.py (path:/tmp/pip-73nOlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:50:23 INFO - Running setup.py (path:/tmp/pip-LrprIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:50:23 INFO - Running setup.py (path:/tmp/pip-PPLDnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:50:23 INFO - Running setup.py (path:/tmp/pip-0MFXH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:50:23 INFO - Running setup.py (path:/tmp/pip-WfCHQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:50:23 INFO - Running setup.py (path:/tmp/pip-1__R7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:50:23 INFO - Running setup.py (path:/tmp/pip-sc0Xi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:50:23 INFO - Running setup.py (path:/tmp/pip-ijwLRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:50:23 INFO - Running setup.py (path:/tmp/pip-5L0ka3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:50:23 INFO - Running setup.py (path:/tmp/pip-ErAjKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:50:23 INFO - Running setup.py (path:/tmp/pip-A63H2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:50:23 INFO - Running setup.py (path:/tmp/pip-ZktMYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:50:23 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)) 22:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:50:23 INFO - Running setup.py (path:/tmp/pip-PVwMdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:50:24 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)) 22:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:50:24 INFO - Running setup.py (path:/tmp/pip-zTwSLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:50:24 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)) 22:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:50:24 INFO - Running setup.py (path:/tmp/pip-BQzHYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:50:24 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)) 22:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:50:24 INFO - Running setup.py (path:/tmp/pip-XmzbjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:50:24 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)) 22:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:50:24 INFO - Running setup.py (path:/tmp/pip-A3KL91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:50:24 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)) 22:50:24 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)) 22:50:24 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)) 22:50:24 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)) 22:50:24 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)) 22:50:24 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)) 22:50:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:26 INFO - Downloading blessings-1.6.tar.gz 22:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:50:26 INFO - Installing collected packages: blessings 22:50:26 INFO - Running setup.py install for blessings 22:50:26 INFO - Successfully installed blessings 22:50:26 INFO - Cleaning up... 22:50:26 INFO - Return code: 0 22:50:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:50:26 INFO - Reading from file tmpfile_stdout 22:50:26 INFO - Current package versions: 22:50:26 INFO - argparse == 1.2.1 22:50:26 INFO - blessings == 1.6 22:50:26 INFO - blobuploader == 1.2.4 22:50:26 INFO - docopt == 0.6.1 22:50:26 INFO - functools32 == 3.2.3-2 22:50:26 INFO - jsonschema == 2.5.1 22:50:26 INFO - manifestparser == 1.1 22:50:26 INFO - mozInstall == 1.12 22:50:26 INFO - mozcrash == 0.17 22:50:26 INFO - mozdebug == 0.1 22:50:26 INFO - mozdevice == 0.48 22:50:26 INFO - mozfile == 1.2 22:50:26 INFO - mozhttpd == 0.7 22:50:26 INFO - mozinfo == 0.9 22:50:26 INFO - mozleak == 0.1 22:50:26 INFO - mozlog == 3.3 22:50:26 INFO - moznetwork == 0.27 22:50:26 INFO - mozprocess == 0.23 22:50:26 INFO - mozprofile == 0.28 22:50:26 INFO - mozrunner == 6.12 22:50:26 INFO - mozscreenshot == 0.1 22:50:26 INFO - mozsystemmonitor == 0.3 22:50:26 INFO - moztest == 0.7 22:50:26 INFO - mozversion == 1.4 22:50:26 INFO - psutil == 3.1.1 22:50:26 INFO - requests == 1.2.3 22:50:26 INFO - wsgiref == 0.1.2 22:50:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:26 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 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:26 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 22:50:26 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 22:50:26 INFO - Using env: {'DISPLAY': ':0', 22:50:26 INFO - 'HOME': '/home/cltbld', 22:50:26 INFO - 'LANG': 'en_US.UTF-8', 22:50:26 INFO - 'LANGUAGE': 'en_US:en', 22:50:26 INFO - 'LOGNAME': 'cltbld', 22:50:26 INFO - 'MAIL': '/var/mail/cltbld', 22:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:26 INFO - 'MOZ_NO_REMOTE': '1', 22:50:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:26 INFO - 'NO_EM_RESTART': '1', 22:50:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:26 INFO - 'PWD': '/builds/slave/test', 22:50:26 INFO - 'SHELL': '/bin/bash', 22:50:26 INFO - 'SHLVL': '1', 22:50:26 INFO - 'TERM': 'linux', 22:50:26 INFO - 'TMOUT': '86400', 22:50:26 INFO - 'USER': 'cltbld', 22:50:26 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:26 INFO - '_': '/tools/buildbot/bin/python'} 22:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 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)) 22:50:26 INFO - Cleaning up... 22:50:26 INFO - Return code: 0 22:50:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f781d523f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', '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 22:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:50:26 INFO - Using env: {'DISPLAY': ':0', 22:50:26 INFO - 'HOME': '/home/cltbld', 22:50:26 INFO - 'LANG': 'en_US.UTF-8', 22:50:26 INFO - 'LANGUAGE': 'en_US:en', 22:50:26 INFO - 'LOGNAME': 'cltbld', 22:50:26 INFO - 'MAIL': '/var/mail/cltbld', 22:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:26 INFO - 'MOZ_NO_REMOTE': '1', 22:50:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:26 INFO - 'NO_EM_RESTART': '1', 22:50:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:26 INFO - 'PWD': '/builds/slave/test', 22:50:26 INFO - 'SHELL': '/bin/bash', 22:50:26 INFO - 'SHLVL': '1', 22:50:26 INFO - 'TERM': 'linux', 22:50:26 INFO - 'TMOUT': '86400', 22:50:26 INFO - 'USER': 'cltbld', 22:50:26 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:26 INFO - '_': '/tools/buildbot/bin/python'} 22:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:50:27 INFO - Cleaning up... 22:50:27 INFO - Return code: 0 22:50:27 INFO - Running post-action listener: _resource_record_post_action 22:50:27 INFO - Running post-action listener: _start_resource_monitoring 22:50:27 INFO - Starting resource monitoring. 22:50:27 INFO - [mozharness: 2016-09-02 05:50:27.132265Z] Finished create-virtualenv step (success) 22:50:27 INFO - [mozharness: 2016-09-02 05:50:27.132708Z] Running install step. 22:50:27 INFO - Running pre-action listener: _resource_record_pre_action 22:50:27 INFO - Running main action method: install 22:50:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:50:27 INFO - Reading from file tmpfile_stdout 22:50:27 INFO - Detecting whether we're running mozinstall >=1.0... 22:50:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:50:27 INFO - Reading from file tmpfile_stdout 22:50:27 INFO - Output received: 22:50:27 INFO - Usage: mozinstall [options] installer 22:50:27 INFO - Options: 22:50:27 INFO - -h, --help show this help message and exit 22:50:27 INFO - -d DEST, --destination=DEST 22:50:27 INFO - Directory to install application into. [default: 22:50:27 INFO - "/builds/slave/test"] 22:50:27 INFO - --app=APP Application being installed. [default: firefox] 22:50:27 INFO - mkdir: /builds/slave/test/build/application 22:50:27 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'] 22:50:27 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 22:50:43 INFO - Reading from file tmpfile_stdout 22:50:43 INFO - Output received: 22:50:43 INFO - /builds/slave/test/build/application/firefox/firefox 22:50:43 INFO - Running post-action listener: _resource_record_post_action 22:50:43 INFO - [mozharness: 2016-09-02 05:50:43.564571Z] Finished install step (success) 22:50:43 INFO - [mozharness: 2016-09-02 05:50:43.564851Z] Running run-tests step. 22:50:43 INFO - Running pre-action listener: _resource_record_pre_action 22:50:43 INFO - Running main action method: run_tests 22:50:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:50:43 INFO - Python 2.7.3 22:50:43 INFO - Return code: 0 22:50:43 INFO - grabbing minidump binary from tooltool 22:50: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')]} 22:50:43 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 0x1d45090>, '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 0x1d489b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:50:43 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 22:50:43 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 22:50:43 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 22:50:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:50:43 INFO - Return code: 0 22:50:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:50:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:50:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:50:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:50:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:50:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 22:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 22:50:43 INFO - Using env: {'DISPLAY': ':0', 22:50:43 INFO - 'HOME': '/home/cltbld', 22:50:43 INFO - 'LANG': 'en_US.UTF-8', 22:50:43 INFO - 'LANGUAGE': 'en_US:en', 22:50:43 INFO - 'LOGNAME': 'cltbld', 22:50:43 INFO - 'MAIL': '/var/mail/cltbld', 22:50:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:50:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:43 INFO - 'MOZ_NO_REMOTE': '1', 22:50:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:50:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:43 INFO - 'NO_EM_RESTART': '1', 22:50:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:43 INFO - 'PWD': '/builds/slave/test', 22:50:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:50:43 INFO - 'SHELL': '/bin/bash', 22:50:43 INFO - 'SHLVL': '1', 22:50:43 INFO - 'TERM': 'linux', 22:50:43 INFO - 'TMOUT': '86400', 22:50:43 INFO - 'USER': 'cltbld', 22:50:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963', 22:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:43 INFO - '_': '/tools/buildbot/bin/python'} 22:50:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 22:50:43 INFO - mozversion application_buildid: 20160901215642 22:50:43 INFO - mozversion application_changeset: 4c4ce191fc9b444f1f47272293e001707538c105 22:50:43 INFO - mozversion application_display_name: Nightly 22:50:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:50:43 INFO - mozversion application_name: Firefox 22:50:43 INFO - mozversion application_remotingname: firefox 22:50:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:50:43 INFO - mozversion application_vendor: Mozilla 22:50:43 INFO - mozversion application_version: 51.0a1 22:50:43 INFO - mozversion platform_buildid: 20160901215642 22:50:43 INFO - mozversion platform_changeset: 4c4ce191fc9b444f1f47272293e001707538c105 22:50:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:50:43 INFO - mozversion platform_version: 51.0a1 22:50:43 INFO - using testdate: 1472795443 22:50:43 INFO - actual date: 1472795443 22:50:43 INFO - starting webserver on 'localhost:54419' 22:50:43 INFO - SUITE-START | Running 2 tests 22:50:43 INFO - TEST-START | dromaeo_css 22:50:43 INFO - operating with platform_type : linux_ 22:50:43 INFO - Initialising browser for dromaeo_css test... 22:50:43 INFO - TEST-INFO | started process 20223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpitdem7/profile http://localhost:54419/getInfo.html) 22:50:48 INFO - PROCESS | 20223 | __metrics Screen width/height:1600/1200 22:50:48 INFO - PROCESS | 20223 | colorDepth:24 22:50:48 INFO - PROCESS | 20223 | Browser inner width/height: 1024/697 22:50:48 INFO - PROCESS | 20223 | __metrics 22:50:49 INFO - PROCESS | 20223 | 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" 22:50:49 INFO - PROCESS | 20223 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:50:49 INFO - PROCESS | 20223 | console.error: PushService: 22:50:49 INFO - PROCESS | 20223 | stateChangeProcessEnqueue: Error transitioning state 22:50:49 INFO - PROCESS | 20223 | UnknownError 22:50:50 INFO - TEST-INFO | 20223: exit 0 22:50:50 INFO - Browser initialized. 22:50:50 INFO - Running cycle 1/1 for dromaeo_css test... 22:50:50 INFO - TEST-INFO | started process 20303 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpitdem7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:51:02 INFO - PROCESS | 20303 | RSS: Main: 180699136 22:51:02 INFO - PROCESS | 20303 | 22:51:53 INFO - PROCESS | 20303 | 1472795513729 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] 22:54:07 INFO - PROCESS | 20303 | Cycle 1(1): loaded http://localhost:54419/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54419/tests/dromaeo/cssquery-ext.html) 22:54:08 INFO - PROCESS | 20303 | RSS: Main: 278446080 22:54:08 INFO - PROCESS | 20303 | 22:57:10 INFO - PROCESS | 20303 | Cycle 1(1): loaded http://localhost:54419/tests/dromaeo/cssquery-ext.html (next: http://localhost:54419/tests/dromaeo/cssquery-jquery.html) 22:57:11 INFO - PROCESS | 20303 | RSS: Main: 277843968 22:57:11 INFO - PROCESS | 20303 | 23:00:13 INFO - PROCESS | 20303 | Cycle 1(1): loaded http://localhost:54419/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54419/tests/dromaeo/cssquery-mootools.html) 23:00:14 INFO - PROCESS | 20303 | RSS: Main: 258080768 23:00:14 INFO - PROCESS | 20303 | 23:03:16 INFO - PROCESS | 20303 | Cycle 1(1): loaded http://localhost:54419/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54419/tests/dromaeo/cssquery-prototype.html) 23:03:17 INFO - PROCESS | 20303 | RSS: Main: 220491776 23:03:17 INFO - PROCESS | 20303 | 23:06:20 INFO - PROCESS | 20303 | Cycle 1(1): loaded http://localhost:54419/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54419/tests/dromaeo/cssquery-yui.html) 23:06:20 INFO - PROCESS | 20303 | RSS: Main: 218705920 23:06:20 INFO - PROCESS | 20303 | 23:09:23 INFO - PROCESS | 20303 | Cycle 1(1): loaded http://localhost:54419/tests/dromaeo/cssquery-yui.html (next: http://localhost:54419/tests/dromaeo/cssquery-dojo.html) 23:09:24 INFO - PROCESS | 20303 | RSS: Main: 309084160 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | __start_tp_report 23:09:24 INFO - PROCESS | 20303 | _x_x_mozilla_page_load 23:09:24 INFO - PROCESS | 20303 | _x_x_mozilla_page_load_details 23:09:24 INFO - PROCESS | 20303 | |i|pagename|runs| 23:09:24 INFO - PROCESS | 20303 | |0;dojo.html;2582.548054090534;2598.0469990473166;2615.7907367410608;2659.375046863988;2669.2392668089597;4612.10064037512;4650.627949764019;4680.719156850591;4711.434627844659;4789.0182512584925;4689.742064186468;4756.26277926921;4774.4270687517455;4778.80884764609;4844.806207751686;4343.565643435647;4345.044090300129;4429.313456414261;4498.640108791289;4519.118771839492;4419.646428285745;4444.999875028109;4458.041521072969;4473.373727678128;4488.798004089807;4231.090315582134;4312.482502099737;4353.259945809188;4363.149185908749;4393.51671316155;4362.8909277268;4369.847055353048;4374.846880359172;4380.759058251827;4427.579379958899;4362.040351122748;4375.2124617568825;4378.496472905625;4414.3157810539415;4431.800568974404;4589.0821835633005;4756.809727610926;4838.322634831126;4842.975785121087;4916.557509824097;5158.793648254103;5172.534471897509;5190.636655434121;5206.453322401169;5208.505192006791;5219.6085293602755;5224.843254702365;5228.032813929435;5232.92150617741;5283.498067683564;4631.8147274109;4694.389729335165;4729.219678753036;4742.027884283713;4810.2063159578975;5224.869378265574;5228.764705588258;5229.607779416559;5229.973850130725;5231.686098834082;4998.725070121105;5002.424721157041;5006.574441172541;5010.298558201855;5056.84829455117;4895.951040489549;5022.447530771612;5064.037832811754;5067.037262920034;5143.8456846294675;5021.6735912165595;5035.244713292962;5038.722870242099;5041.949580504222;5110.514501122388;5037.244413338029;5040.36995375578;5041.949580504148;5043.344365232496;5044.041632089891;4206.242876282237;4240.342746874239;4245.002424430257;4293.570642935744;4351.586599273064;3821.7133714970932;3842.9231415372096;3941.881743547698;3945.467361906106;3949.7827619481204;4475.948863353689;4482.731036137842;4484.192845287879;4535.024969631545;4542.500324964251;4921.409430868319;5144.202648589475;5170.586353091744;5180.48195180479;5183.97408012965;4367.978160109243;4390.604845563915;4426.468823741172;4473.821047158142;4477.059817438374;4540.387047748578;4575.794089265991;4587;4589.701669391479;4640.187967105743;4792.664513484023;4803.135435621622;4806.615470762331;4812.927806082982;4862.246351815475;4866.1727506323305;5062.696238225718;5073.2897394365555;5116.872078198075;5125.79496820123;4861.829835955725;4863.732494712684;4867.659263851497;4911.238757992517;4927.211646136601;5046.646734728533;5049.899002020013;5051.722155281494;5054.29239906406;5062.493750624908;4903.166461701448;5047.924281135713;5133.255677926605;5147.6911385317;5185.792568297376;5021.472745361684;5031.119554078095;5032.798688052437;5034.395872495324;5061.797528098835;5016.824411145592;5020.221865610836;5023.723695196799;5024.296598476143;5079.034983353151;4741.739204343657;4820;4832.299316599003;4867;4926.959292773351;5014.648974571745;5014.924776128434;5015.774290156879;5016.824411145592;5075.0357432087785;4650.255959046536;4892.706437613754;4902.558769710743;4908.239222920453;4958.900821983612 23:09:24 INFO - PROCESS | 20303 | |1;ext.html;568.2697733412566;571.3201290464347;577.474498206632;583.9474447299742;587.2102022779361;27123.32191695206;37859.62140378595;39391.40912886305;39524.407133893015;42907.35638965418;39155.60844391555;39393.8030309848;39461.80269098658;39484.210315793665;39487.40768888462;7681.5775132367835;7838.804029899241;7841.803954901116;7842.490238134503;7843.137254901956;7674.616269186518;7735.458517903721;7754.767356979271;7760.534367937914;7763.611819409035;7743.457957942946;7746.225377462237;7798.80502987427;7803;7806.258405451473;7710;7728.111267204231;7758.185390533969;7758.7672369829;7761.22387761225;7664.3102120808835;7730.497517661334;7730.536167829948;7733.729319473791;7738.4969976951315;37411;37631.4355284671;37672.24655506875;37673.81163094167;37709.81145094257;2465.4083020075054;2552.412945022655;2581.354661334666;2619.9607005894927;2637.643918071055;27542.862285688443;27856.582151267747;28072.298192545146;28135.437291254264;28221.435571288665;3230.144011836865;3326.9334613307597;3340.1816554944194;3354.8490317935753;3358.865645374182;241455;242976;244355;245203;246428;49751.75124124356;50552.24171637429;50597.49402506001;50601;50634;31671.84164079165;32687;32783.50824737631;46248.3062754059;46434.303485447745;14282.857171428153;14372.784408233885;14439.783403248959;14451.92774036123;14577;10756.677299681021;10814.513346899408;10827.187960902964;10827.67516974492;10830.945845270724;17314.480565583053;17319.22063507145;17357.47927562175;17388.130593470276;17402.39091631787;6467.318497190764;6617.187695229508;6663.566868153554;6668;6717.5893062457435;783.9960800195962;865.7489328094782;923.9676611318572;925.7546750111226;930.0513476254318;27575.862120689668;27701.168964931083;27774.44451111007;28067.157985260474;28254;906.8866391701038;946.4936259101486;947.0860619502214;951.4671783801093;1048.8112139814755;14201.502947396792;14844.925775371054;14858.252233865103;14901.403943842124;14957.551273461813;389844;393083.89909311605;393201;393558;395792;47862.76068619704;48115.75942120267;48606;48703;48742.75628621905;1055.5144633468537;1058.8941105889503;1128.7742451509566;1163.4822503985645;1171.1684703860356;25482;28516.146450480654;28984.275393115342;29261.414771704873;29326.266843328234;39917.2016559673;40305.79847100746;40365.79817100896;40383.79808100941;40406.39390409205;302356;302728;303223;303649;304730;21996.780032199473;24332.51334973255;24831.379215519755;24959.12643057484;25264.115755948453;39778.60221397749;40157.196856063296;40519.59480405158;40985.44232922718;41615.37576936288;34882.94197341422;35093;36221.09447263734;36230.27539449144;36698.26603467969;32501.512477313332;34770.130746731535;35013.649863501036;35025.82487087548;35285.64714352824;34336.65663343335;34682;34770.53951197631;35041.824790875886;35414.82292588521;30337.848310758305;32748.672513274563;32969.67030329666;33006;33085.503717443775;24483.63274550919;24667.136650217155;24723.8763806187;24854.627180592666;25327.746722533277 23:09:24 INFO - PROCESS | 20303 | |2;jquery.html;340.15301898273304;356.2660918507874;368.24693501788846;370.3223101723845;383.21823480100596;12409.875901240985;14393.064450810703;16110.11394373309;16204.189790510456;16327.591810204765;15508.5347439577;15906;16109.758353624677;16194.35222591098;16821.915890420532;4309.418228539154;4620.445546534404;4651.348811166439;4652.092841895832;4657.836975705851;3382.5433566468582;4570.97714511427;4640.048789998043;4640.582347588716;5044.722540260284;5289.867753306179;5373.194020896877;5376.38171610264;5379.273798037274;5380.031594313042;5115.104856650071;5320.228566857822;5348.839534813942;5361.490658387447;5367.704776237305;5191.221316802472;5208.401033881114;5210.322658054429;5210.348706411699;5214.322138122057;12501.56244531437;15976.840231597651;20165.0925708342;20436.693449598268;20542.38372848817;1772.5745821002995;1788.8121747216485;1797.613513094678;2032.7763945965933;2222.733272007353;9253.074692530761;9284.721458356193;9287.535623218879;9747;9867.434467038549;1625.5285967069535;1705.5650809043739;1721.4835549335148;1779.8487128593956;1803.0804289812158;64112.07210624271;70533.29466705318;71259.28740712578;71794;74696.25303747001;9732.513374331254;9750.756231094138;10244.951009798071;10250.333728307785;10543.367397956146;14117.152970821784;14232.644183895278;14244.786328205086;14257.287135643384;14261.78607320891;6767.154105736783;6991.720331186695;7051.435885129177;7213.314735100157;7272.636368181676;6589.53873228879;6591.736330546821;6729.1252137221845;6784.830379240559;6800.523963322619;4333.804978775899;4343.609075183185;4348.456442944632;4458.398116254329;4465.330200469955;1704.7613334133232;1736.2273788164146;1760.5334586334632;1762.5946032412617;1799.6220793633483;802.5104686141449;805.2551389964377;836.0886633569438;874.6239117179674;884.2395539835736;16155;16370.181490925406;17230.569235768955;17376.21807018687;17504.91247543754;844.8774927635459;848.8896443462311;849.1169184048521;869.4088020146235;887.2281115429618;8953.418027828171;9139.862902056475;9760.316777825485;9833.311668183302;10083.697489075339;356447;359296;365126;365142;367022;76165;76900.23099769042;77337.61331193308;77451;79013;906.6237511432681;919.3794188922451;924.8890133184061;950.8431108867098;951.0632027452954;14955.476558320406;15010.099394036393;15445.227738612799;15700.92149539245;15709.293081811118;36074.4588831173;36288.81855590705;37454.62545374512;37511.43732843955;37711.24577508489;46311.30533042074;48108.759456203894;48260.27609585789;48924.510754892;49373.50626493689;6629.8397780389205;7243.674034668347;7298;7373.18894921569;7434.293742094493;10364.222683298633;10674.199435042248;10886.129109671048;10987.945060274647;11026.228164028762;9199.494027828534;9247.445153290824;9383.718488445085;9425.622975081194;9930.006999300012;8202.974628171478;8975.730728078168;9506.239500840054;9786.706398808046;9832.705018849447;9466.952665236906;9570.61717531291;9592.424454532751;9647.855282170913;9818.85271720939;6989.825254368681;7164.319389657974;7250.093738282715;7302.087239095114;7314.305141011595;8078.959605201936;9245.121713437213;9490.575041756178;9554.808903822024;9902.108810207115 23:09:24 INFO - PROCESS | 20303 | |3;mootools.html;1250.3435696259457;1363.1071648070515;1399.5731301952903;1475.8081449411575;1707.2317457144281;7493.475456718025;7867.331276841468;7936.563489008102;8158.592070396486;8166.101728809841;8035.959820200906;8115.1073381927945;8123.75628731139;8134.95932520338;8137.064237612676;2222.944426389341;2231.040652519416;2232.218723446797;2233.542123864604;2240.7759224077627;2193.5941850757663;2239.1155493580027;2239.1155493580027;2241.674957131215;2241.9551608967813;2146.7548821683463;2164.4588852786806;2193.3748881568754;2201.0535469748;2205.0848897707433;2141.518158414352;2152.709384233124;2167.0681606909025;2169.1973969631335;2174.5759576882515;2116.534362440261;2143.132031527234;2155.8814265215406;2158.438805910459;2165.4694599822988;8042.839143217161;8096.554689492075;8101.95949020257;8106.310963568104;8108.2297181767635;4377.562243775597;4553.362529245908;4565.292379681155;4588.151192029485;4601.056783359403;2842.5878247653973;3003.564483149953;3037.787354885159;3052.9542056869163;3056.006797790727;921.6083164654995;929.5027160469117;945.6264775413673;959.1799011844912;970.45169479244;10924.399158046304;10925.07136893365;10952.726181845437;10970.835437468364;10975.560977560888;4028.879133625996;4038.6970977176243;4110.568390319033;4111.691623128278;4141.337386018223;8715.99766026915;8736.606852691644;8745;8748.081451447633;8748.606312715943;4436.6007059364165;4437.689361744711;4444.6666500012625;4452.998075433002;4456.532064133284;3473.149078475739;3764.8117594120185;3873.593272706325;3879.321118804198;4124.628783409507;3425.092350527112;3433.828308584561;3551.9644803551632;3576.8390422431053;3598.4962105305276;1030.314840630987;1051.3639248254697;1054.852320675106;1055.1242468750918;1061.5010944855906;679.7756740275697;735.4778107543597;742.2392048150581;779.918108598589;842.5281842168405;5046.116929537389;5071.239314102914;5110.361204849394;5242;5409.188621706697;394.4635295997441;406.4249087541155;409.1836785612722;410.0200520755397;419.25372836351346;3376.7805092668896;3377.966220337765;3379.7803142795638;3388.6950174484414;3480.182157193108;9734.805303893883;10123;10166.847497287547;10174.847377289347;10193.23550733698;10100.595976160872;10102.848457273147;10113.494325283853;10131.746706332253;10143.08712215904;7573.35626471745;7615.048118985127;7635.961820190752;7642.770716878503;7645.350145237606;5028.321176641107;5121.820736274212;5175.81884634036;5177.663451875616;5399.244105825266;9142.817143657223;9198.908010920073;9222.44665320056;9269.119433653792;9277.721668349961;9336.95331523338;9405.764855878659;9465.195458385839;9468.715938521855;9536.380135291183;5795.971020144872;6201.658908759881;6335.081413195153;6527;6614;7732.9226707732205;7799.376049916106;7807.765767026998;7810.140884502586;7838.960805195938;6806.625635589892;7003.824904377431;7053.541519801196;7065.6820443081215;7165.319294666998;6652.135222421054;6797.1843378794865;6841.3842754152365;6889.104416425833;6904.654767261516;6267.185265915402;6313.494920406449;6331.556791024584;6354.3963323484195;6373.6175829450385;4409.294512877924;4416.8012439440945;4422.77886105687;4605.861824145281;4687.531246875285;4522.728636281834;4551.681382303207;4557.384753058427;4640.489546149988;4646.62826973848 23:09:24 INFO - PROCESS | 20303 | |4;prototype.html;285.79851204900547;293.5977710536565;294.079531067758;296.3347285344402;304.7394477721548;4834.009028149229;4855.902881942359;4870.878228044301;4885.828995985141;4906.705597664142;4755.8811029724275;4774.73738944358;4835.742706896206;4837.492063333352;4840.588549973248;3609.2420591675777;3719.479272901796;3723.4042553191493;3731.1977924746175;3763.7177211709095;3699.260147970403;3778.2821263959895;3801.3157631626295;3814.809259537012;3851.4993050903345;3775.4903088083033;3775.8678446254385;3776.9811150944206;3793.7806383265684;3801.1257410795533;3537.5224344713392;3731;3778.9811050944845;3807.1053302473897;3814.694824414054;3280.1471617379652;3281.9343613127844;3287.227501537137;3291.572095627553;3303.884364047247;4643.512431194744;4644.397682799428;4651.860294227936;4655.766221951152;4661.3241080043545;4225.260579398624;4269.188854117708;4279.229738647043;4279.700420970534;4289.957100428987;4894.241392584155;4902.852914412573;4905.650945989845;4913.680610760289;4933.901321973541;3545.450455179451;3547.5455063423874;3553.271579326258;3574.249407624402;3589.335972845032;5189.6886186828915;5199.272101905736;5226.8693282667855;5267.868303292448;5282.498162674578;3562.5012498250267;3633.8916630427443;3641.126129728899;3675.4303083022173;3680.595134535199;3694.743787112395;3709.3879509880567;3711.2763011212555;3712.3132220539287;3713.7400381973544;4185.686073544496;4214.241436541452;4236.512801027921;4245.087306229114;4246.278132717445;4956.950430495721;4961.528654777862;4976.303317535548;4994.500549945049;4998.850034498971;1209.9939500302619;1282.6152154353656;1295.1710905020607;1298.69480672042;1298.9608313349283;787.799111226645;827.6937533112666;830.9916900830914;840.9369297302727;843.7173546861748;4311.744632802277;4315.8273669053515;4447.844325448658;4470.0389416275;4485.237509623372;5138.332016837787;5146.073706732749;5161.612879034087;5183.015227106837;5207.427183009866;4457.977710111429;4465.977670111629;4482.932756008662;4497.370368148462;4523.095380923828;2617.738226177405;2697.6223328733995;2720.5375086235385;2721.414895797413;2721.6053672217813;4855.174620314555;4861.222204447272;4862.905846184651;4863.975680121647;4903;5074.797008119634;5255.816046438356;5275.683458992396;5283.365996080495;5292.179712144623;4808.61531077513;4840.540148685799;4851.151048566487;4860.639021074105;4871.926921096116;5117.667351622133;5122.718250496258;5130.307408499804;5145.459726728789;5168.689878607295;4903.803847846046;5031.547160755549;5054.545090941833;5065.036953382312;5088.771005304696;4869.514797986506;4881.219004959189;4888.291197776373;4903.577962390867;4906.460289368238;3691.298653255873;3694;3698.556173259226;3718.4794128821445;3727.1427571659215;4945.2582112683385;5198.792048318025;5208.192730126836;5223.634345595924;5229.346331708536;4978.726170060537;4980.5019498049905;4984.800607975641;4992.950070499249;5009.918899876477;4924.507549245047;4942.209246520683;4982.10322142018;4983.6760610560195;4990.900181996412;4667.83662571822;4842.661013729005;4844.273358996179;4848.393950756155;4879.756012199447;4821.566059054561;4893.559579637849;5052.747362631927;5054.21659642756;5069.188929771219;4876.268924531741;4912.413937825336;4949.505049495022;4950.217921548167;4965.553100220997 23:09:24 INFO - PROCESS | 20303 | |5;yui.html;614.8800983808158;619.2746770076964;664.3721683009558;683.9952080568276;688.1741909708351;12069.577564785235;12826.230426174425;12854.742905141891;12858.292793896331;12914.289714065735;11895.940520297387;11978.580749673763;12002.279863208214;12004.879951200508;12008.159428839985;597.8595030167907;735.4006484714954;759.7948553890446;761.9885701714479;763.9388848892103;722.2308241722549;747.2490147401853;747.4580928826584;752.7026824404358;753.8303881626617;797.9122296547375;799.3684988858795;799.5842162075717;799.6241766369826;802.3100133884855;606.205870309891;791.4024911192071;792.742358733411;792.9682812687486;793.4287313134563;563.8111232736996;581.2066529187671;582.8426324892255;584.7485581200082;585.1281590430228;11450.599229026944;11480.196386252883;11507.654770356903;11527.423628818526;11529.077673786162;12033.51865925362;12629.621111366674;12806;12991.675208119872;13198.406071726795;9406.153446189808;11860.88139118615;11909.583164589285;11943.820842687366;12113.273203607723;3939.862104826317;3978.9801050994847;4041.7574945503366;4091.672666186728;4099.282625540519;56159;57130;57209.427905720826;57265.713671431375;57370.713146434;8970.596323165473;9099.863502047474;9135.177833994972;9309.860352094724;9400.388974716621;17359.21883515245;17450.21474033646;17462.30150793954;17482.125893705517;17589.29642814299;6126.663033533109;6187.731515039496;6309;6449.419552240321;6463.8060858173385;3322.80063196209;3482.9651703483146;3512.473129030666;3565.750397472204;3593.6945359644724;2787.080263513036;2936.985315073411;2942.6027486289504;2944.20506463258;2945.3078526546246;515.9742012899341;528.4055437632663;539.2477493895993;542.1244689825982;547.5756288876073;492.0527983631495;516.8423630792645;520.6563667979116;561.2339157050596;565.1155940952397;7682.73110441143;7686.040059784719;7718.266764657348;7977;8078.272955434039;453.20688794609435;515.8813472901254;532.052945756554;533.2747463449705;577.5177726598338;3554.20030493137;3591.748577599543;3611.2416392557334;3628.455731640275;3686.09690625793;58339.70830145907;58671.773052482524;59271.11093333604;59814.401855981756;65638.34361656418;82626;83751.5812420934;88787;89443;89964.10035899557;47705.28442073372;70116.94824577635;70527;73162;73860.63069684617;6635.4359879410795;7057.611831349325;7068.29317068289;7090.929090709027;7161.460286038527;30055.549166762077;37472.81263593664;37619.62380376161;37663.435048473744;39315.60684393119;38592;46255.53744462512;50258.748706256236;56853.71573142108;59637;10471.476426178813;11068.501917413896;11199.60801371948;11354.943225283821;11425.714357141138;12287;12461.626151215478;12466.314352710688;12556.811647825107;13257.602271931857;9452.905470945203;10892.346459212473;11285.43572821372;11557.595484158013;11793.528258869892;10639.734006649896;10783.299085559414;11363.261388009752;11654.825177622512;12284.078694098158;11258.549658013588;11334.773304533699;11630.360330181922;11701.356425396345;12241;9663.806723865342;9701.73877395934;10177.542010609392;10517.579296828042;10896.400697961688;9214.631414743335;9736.269779766404;9895.939502717027;10027.298089133983;10039.548220329956 23:09:24 INFO - PROCESS | 20303 | __end_tp_report 23:09:24 INFO - PROCESS | 20303 | __start_cc_report 23:09:24 INFO - PROCESS | 20303 | _x_x_mozilla_cycle_collect,950 23:09:24 INFO - PROCESS | 20303 | __end_cc_report 23:09:24 INFO - PROCESS | 20303 | __startTimestamp1472796564229__endTimestamp 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | ------- Summary: start ------- 23:09:24 INFO - PROCESS | 20303 | Number of tests: 6 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | [#0] dojo.html Cycles:165 Average:4723.39 Median:4861.83 stddev:496.05 (10.2%) stddev-sans-first:468.28 23:09:24 INFO - PROCESS | 20303 | Values: 2582.5 2598.0 2615.8 2659.4 2669.2 4612.1 4650.6 4680.7 4711.4 4789.0 4689.7 4756.3 4774.4 4778.8 4844.8 4343.6 4345.0 4429.3 4498.6 4519.1 4419.6 4445.0 4458.0 4473.4 4488.8 4231.1 4312.5 4353.3 4363.1 4393.5 4362.9 4369.8 4374.8 4380.8 4427.6 4362.0 4375.2 4378.5 4414.3 4431.8 4589.1 4756.8 4838.3 4843.0 4916.6 5158.8 5172.5 5190.6 5206.5 5208.5 5219.6 5224.8 5228.0 5232.9 5283.5 4631.8 4694.4 4729.2 4742.0 4810.2 5224.9 5228.8 5229.6 5230.0 5231.7 4998.7 5002.4 5006.6 5010.3 5056.8 4896.0 5022.4 5064.0 5067.0 5143.8 5021.7 5035.2 5038.7 5041.9 5110.5 5037.2 5040.4 5041.9 5043.3 5044.0 4206.2 4240.3 4245.0 4293.6 4351.6 3821.7 3842.9 3941.9 3945.5 3949.8 4475.9 4482.7 4484.2 4535.0 4542.5 4921.4 5144.2 5170.6 5180.5 5184.0 4368.0 4390.6 4426.5 4473.8 4477.1 4540.4 4575.8 4587.0 4589.7 4640.2 4792.7 4803.1 4806.6 4812.9 4862.2 4866.2 5062.7 5073.3 5116.9 5125.8 4861.8 4863.7 4867.7 4911.2 4927.2 5046.6 5049.9 5051.7 5054.3 5062.5 4903.2 5047.9 5133.3 5147.7 5185.8 5021.5 5031.1 5032.8 5034.4 5061.8 5016.8 5020.2 5023.7 5024.3 5079.0 4741.7 4820.0 4832.3 4867.0 4927.0 5014.6 5014.9 5015.8 5016.8 5075.0 4650.3 4892.7 4902.6 4908.2 4958.9 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | [#1] ext.html Cycles:180 Average:45989.98 Median:35350.24 stddev:84155.44 (238.1%) stddev-sans-first:84322.42 23:09:24 INFO - PROCESS | 20303 | Values: 568.3 571.3 577.5 583.9 587.2 27123.3 37859.6 39391.4 39524.4 42907.4 39155.6 39393.8 39461.8 39484.2 39487.4 7681.6 7838.8 7841.8 7842.5 7843.1 7674.6 7735.5 7754.8 7760.5 7763.6 7743.5 7746.2 7798.8 7803.0 7806.3 7710.0 7728.1 7758.2 7758.8 7761.2 7664.3 7730.5 7730.5 7733.7 7738.5 37411.0 37631.4 37672.2 37673.8 37709.8 2465.4 2552.4 2581.4 2620.0 2637.6 27542.9 27856.6 28072.3 28135.4 28221.4 3230.1 3326.9 3340.2 3354.8 3358.9 241455.0 242976.0 244355.0 245203.0 246428.0 49751.8 50552.2 50597.5 50601.0 50634.0 31671.8 32687.0 32783.5 46248.3 46434.3 14282.9 14372.8 14439.8 14451.9 14577.0 10756.7 10814.5 10827.2 10827.7 10830.9 17314.5 17319.2 17357.5 17388.1 17402.4 6467.3 6617.2 6663.6 6668.0 6717.6 784.0 865.7 924.0 925.8 930.1 27575.9 27701.2 27774.4 28067.2 28254.0 906.9 946.5 947.1 951.5 1048.8 14201.5 14844.9 14858.3 14901.4 14957.6 389844.0 393083.9 393201.0 393558.0 395792.0 47862.8 48115.8 48606.0 48703.0 48742.8 1055.5 1058.9 1128.8 1163.5 1171.2 25482.0 28516.1 28984.3 29261.4 29326.3 39917.2 40305.8 40365.8 40383.8 40406.4 302356.0 302728.0 303223.0 303649.0 304730.0 21996.8 24332.5 24831.4 24959.1 25264.1 39778.6 40157.2 40519.6 40985.4 41615.4 34882.9 35093.0 36221.1 36230.3 36698.3 32501.5 34770.1 35013.6 35025.8 35285.6 34336.7 34682.0 34770.5 35041.8 35414.8 30337.8 32748.7 32969.7 33006.0 33085.5 24483.6 24667.1 24723.9 24854.6 25327.7 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | [#2] jquery.html Cycles:180 Average:23184.82 Median:5199.81 stddev:60136.04 (1156.5%) stddev-sans-first:60280.27 23:09:24 INFO - PROCESS | 20303 | Values: 340.2 356.3 368.2 370.3 383.2 12409.9 14393.1 16110.1 16204.2 16327.6 15508.5 15906.0 16109.8 16194.4 16821.9 4309.4 4620.4 4651.3 4652.1 4657.8 3382.5 4571.0 4640.0 4640.6 5044.7 5289.9 5373.2 5376.4 5379.3 5380.0 5115.1 5320.2 5348.8 5361.5 5367.7 5191.2 5208.4 5210.3 5210.3 5214.3 12501.6 15976.8 20165.1 20436.7 20542.4 1772.6 1788.8 1797.6 2032.8 2222.7 9253.1 9284.7 9287.5 9747.0 9867.4 1625.5 1705.6 1721.5 1779.8 1803.1 64112.1 70533.3 71259.3 71794.0 74696.3 9732.5 9750.8 10245.0 10250.3 10543.4 14117.2 14232.6 14244.8 14257.3 14261.8 6767.2 6991.7 7051.4 7213.3 7272.6 6589.5 6591.7 6729.1 6784.8 6800.5 4333.8 4343.6 4348.5 4458.4 4465.3 1704.8 1736.2 1760.5 1762.6 1799.6 802.5 805.3 836.1 874.6 884.2 16155.0 16370.2 17230.6 17376.2 17504.9 844.9 848.9 849.1 869.4 887.2 8953.4 9139.9 9760.3 9833.3 10083.7 356447.0 359296.0 365126.0 365142.0 367022.0 76165.0 76900.2 77337.6 77451.0 79013.0 906.6 919.4 924.9 950.8 951.1 14955.5 15010.1 15445.2 15700.9 15709.3 36074.5 36288.8 37454.6 37511.4 37711.2 46311.3 48108.8 48260.3 48924.5 49373.5 6629.8 7243.7 7298.0 7373.2 7434.3 10364.2 10674.2 10886.1 10987.9 11026.2 9199.5 9247.4 9383.7 9425.6 9930.0 8203.0 8975.7 9506.2 9786.7 9832.7 9467.0 9570.6 9592.4 9647.9 9818.9 6989.8 7164.3 7250.1 7302.1 7314.3 8079.0 9245.1 9490.6 9554.8 9902.1 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | [#3] mootools.html Cycles:180 Average:5176.80 Median:4555.37 stddev:3020.96 (66.3%) stddev-sans-first:3015.03 23:09:24 INFO - PROCESS | 20303 | Values: 1250.3 1363.1 1399.6 1475.8 1707.2 7493.5 7867.3 7936.6 8158.6 8166.1 8036.0 8115.1 8123.8 8135.0 8137.1 2222.9 2231.0 2232.2 2233.5 2240.8 2193.6 2239.1 2239.1 2241.7 2242.0 2146.8 2164.5 2193.4 2201.1 2205.1 2141.5 2152.7 2167.1 2169.2 2174.6 2116.5 2143.1 2155.9 2158.4 2165.5 8042.8 8096.6 8102.0 8106.3 8108.2 4377.6 4553.4 4565.3 4588.2 4601.1 2842.6 3003.6 3037.8 3053.0 3056.0 921.6 929.5 945.6 959.2 970.5 10924.4 10925.1 10952.7 10970.8 10975.6 4028.9 4038.7 4110.6 4111.7 4141.3 8716.0 8736.6 8745.0 8748.1 8748.6 4436.6 4437.7 4444.7 4453.0 4456.5 3473.1 3764.8 3873.6 3879.3 4124.6 3425.1 3433.8 3552.0 3576.8 3598.5 1030.3 1051.4 1054.9 1055.1 1061.5 679.8 735.5 742.2 779.9 842.5 5046.1 5071.2 5110.4 5242.0 5409.2 394.5 406.4 409.2 410.0 419.3 3376.8 3378.0 3379.8 3388.7 3480.2 9734.8 10123.0 10166.8 10174.8 10193.2 10100.6 10102.8 10113.5 10131.7 10143.1 7573.4 7615.0 7636.0 7642.8 7645.4 5028.3 5121.8 5175.8 5177.7 5399.2 9142.8 9198.9 9222.4 9269.1 9277.7 9337.0 9405.8 9465.2 9468.7 9536.4 5796.0 6201.7 6335.1 6527.0 6614.0 7732.9 7799.4 7807.8 7810.1 7839.0 6806.6 7003.8 7053.5 7065.7 7165.3 6652.1 6797.2 6841.4 6889.1 6904.7 6267.2 6313.5 6331.6 6354.4 6373.6 4409.3 4416.8 4422.8 4605.9 4687.5 4522.7 4551.7 4557.4 4640.5 4646.6 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | [#4] prototype.html Cycles:180 Average:4163.01 Median:4834.88 stddev:1207.51 (25.0%) stddev-sans-first:1175.31 23:09:24 INFO - PROCESS | 20303 | Values: 285.8 293.6 294.1 296.3 304.7 4834.0 4855.9 4870.9 4885.8 4906.7 4755.9 4774.7 4835.7 4837.5 4840.6 3609.2 3719.5 3723.4 3731.2 3763.7 3699.3 3778.3 3801.3 3814.8 3851.5 3775.5 3775.9 3777.0 3793.8 3801.1 3537.5 3731.0 3779.0 3807.1 3814.7 3280.1 3281.9 3287.2 3291.6 3303.9 4643.5 4644.4 4651.9 4655.8 4661.3 4225.3 4269.2 4279.2 4279.7 4290.0 4894.2 4902.9 4905.7 4913.7 4933.9 3545.5 3547.5 3553.3 3574.2 3589.3 5189.7 5199.3 5226.9 5267.9 5282.5 3562.5 3633.9 3641.1 3675.4 3680.6 3694.7 3709.4 3711.3 3712.3 3713.7 4185.7 4214.2 4236.5 4245.1 4246.3 4957.0 4961.5 4976.3 4994.5 4998.9 1210.0 1282.6 1295.2 1298.7 1299.0 787.8 827.7 831.0 840.9 843.7 4311.7 4315.8 4447.8 4470.0 4485.2 5138.3 5146.1 5161.6 5183.0 5207.4 4458.0 4466.0 4482.9 4497.4 4523.1 2617.7 2697.6 2720.5 2721.4 2721.6 4855.2 4861.2 4862.9 4864.0 4903.0 5074.8 5255.8 5275.7 5283.4 5292.2 4808.6 4840.5 4851.2 4860.6 4871.9 5117.7 5122.7 5130.3 5145.5 5168.7 4903.8 5031.5 5054.5 5065.0 5088.8 4869.5 4881.2 4888.3 4903.6 4906.5 3691.3 3694.0 3698.6 3718.5 3727.1 4945.3 5198.8 5208.2 5223.6 5229.3 4978.7 4980.5 4984.8 4993.0 5009.9 4924.5 4942.2 4982.1 4983.7 4990.9 4667.8 4842.7 4844.3 4848.4 4879.8 4821.6 4893.6 5052.7 5054.2 5069.2 4876.3 4912.4 4949.5 4950.2 4965.6 23:09:24 INFO - PROCESS | 20303 | 23:09:24 INFO - PROCESS | 20303 | [#5] yui.html Cycles:180 Average:15661.49 Median:516.41 stddev:21370.23 (4138.2%) stddev-sans-first:21400.32 23:09:24 INFO - PROCESS | 20303 | Values: 614.9 619.3 664.4 684.0 688.2 12069.6 12826.2 12854.7 12858.3 12914.3 11895.9 11978.6 12002.3 12004.9 12008.2 597.9 735.4 759.8 762.0 763.9 722.2 747.2 747.5 752.7 753.8 797.9 799.4 799.6 799.6 802.3 606.2 791.4 792.7 793.0 793.4 563.8 581.2 582.8 584.7 585.1 11450.6 11480.2 11507.7 11527.4 11529.1 12033.5 12629.6 12806.0 12991.7 13198.4 9406.2 11860.9 11909.6 11943.8 12113.3 3939.9 3979.0 4041.8 4091.7 4099.3 56159.0 57130.0 57209.4 57265.7 57370.7 8970.6 9099.9 9135.2 9309.9 9400.4 17359.2 17450.2 17462.3 17482.1 17589.3 6126.7 6187.7 6309.0 6449.4 6463.8 3322.8 3483.0 3512.5 3565.8 3593.7 2787.1 2937.0 2942.6 2944.2 2945.3 516.0 528.4 539.2 542.1 547.6 492.1 516.8 520.7 561.2 565.1 7682.7 7686.0 7718.3 7977.0 8078.3 453.2 515.9 532.1 533.3 577.5 3554.2 3591.7 3611.2 3628.5 3686.1 58339.7 58671.8 59271.1 59814.4 65638.3 82626.0 83751.6 88787.0 89443.0 89964.1 47705.3 70116.9 70527.0 73162.0 73860.6 6635.4 7057.6 7068.3 7090.9 7161.5 30055.5 37472.8 37619.6 37663.4 39315.6 38592.0 46255.5 50258.7 56853.7 59637.0 10471.5 11068.5 11199.6 11354.9 11425.7 12287.0 12461.6 12466.3 12556.8 13257.6 9452.9 10892.3 11285.4 11557.6 11793.5 10639.7 10783.3 11363.3 11654.8 12284.1 11258.5 11334.8 11630.4 11701.4 12241.0 9663.8 9701.7 10177.5 10517.6 10896.4 9214.6 9736.3 9895.9 10027.3 10039.5 23:09:24 INFO - PROCESS | 20303 | -------- Summary: end -------- 23:09:24 INFO - PROCESS | 20303 | 23:09:25 INFO - TEST-INFO | 20303: exit 0 23:09:25 INFO - TEST-OK | dromaeo_css | took 1121384ms 23:09:25 INFO - TEST-START | kraken 23:09:25 INFO - operating with platform_type : linux_ 23:09:25 INFO - Initialising browser for kraken test... 23:09:25 INFO - TEST-INFO | started process 20601 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG0Lw6O/profile http://localhost:54419/getInfo.html) 23:09:30 INFO - PROCESS | 20601 | __metrics Screen width/height:1600/1200 23:09:30 INFO - PROCESS | 20601 | colorDepth:24 23:09:30 INFO - PROCESS | 20601 | Browser inner width/height: 1024/697 23:09:30 INFO - PROCESS | 20601 | __metrics 23:09:30 INFO - PROCESS | 20601 | 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" 23:09:30 INFO - PROCESS | 20601 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:09:31 INFO - PROCESS | 20601 | console.error: PushService: 23:09:31 INFO - PROCESS | 20601 | stateChangeProcessEnqueue: Error transitioning state 23:09:31 INFO - PROCESS | 20601 | UnknownError 23:09:31 INFO - TEST-INFO | 20601: exit 0 23:09:31 INFO - Browser initialized. 23:09:31 INFO - Running cycle 1/1 for kraken test... 23:09:31 INFO - TEST-INFO | started process 20686 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG0Lw6O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:44 INFO - PROCESS | 20686 | RSS: Main: 181993472 23:09:44 INFO - PROCESS | 20686 | 23:09:46 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54419/tests/kraken/driver.html?testName=audio-beat-detection) 23:09:47 INFO - PROCESS | 20686 | RSS: Main: 243126272 23:09:47 INFO - PROCESS | 20686 | 23:09:50 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54419/tests/kraken/driver.html?testName=audio-dft) 23:09:51 INFO - PROCESS | 20686 | RSS: Main: 225546240 23:09:51 INFO - PROCESS | 20686 | 23:09:54 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54419/tests/kraken/driver.html?testName=audio-fft) 23:09:55 INFO - PROCESS | 20686 | RSS: Main: 302448640 23:09:55 INFO - PROCESS | 20686 | 23:09:57 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54419/tests/kraken/driver.html?testName=audio-oscillator) 23:09:57 INFO - PROCESS | 20686 | RSS: Main: 212332544 23:09:57 INFO - PROCESS | 20686 | 23:09:59 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54419/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:10:00 INFO - PROCESS | 20686 | RSS: Main: 215109632 23:10:00 INFO - PROCESS | 20686 | 23:10:03 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54419/tests/kraken/driver.html?testName=imaging-darkroom) 23:10:04 INFO - PROCESS | 20686 | RSS: Main: 253308928 23:10:04 INFO - PROCESS | 20686 | 23:10:07 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54419/tests/kraken/driver.html?testName=imaging-desaturate) 23:10:08 INFO - PROCESS | 20686 | RSS: Main: 247513088 23:10:08 INFO - PROCESS | 20686 | 23:10:11 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54419/tests/kraken/driver.html?testName=json-parse-financial) 23:10:11 INFO - PROCESS | 20686 | RSS: Main: 251088896 23:10:11 INFO - PROCESS | 20686 | 23:10:13 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54419/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:10:14 INFO - PROCESS | 20686 | RSS: Main: 214437888 23:10:14 INFO - PROCESS | 20686 | 23:10:16 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:10:16 INFO - PROCESS | 20686 | RSS: Main: 222052352 23:10:16 INFO - PROCESS | 20686 | 23:10:18 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:10:19 INFO - PROCESS | 20686 | RSS: Main: 221908992 23:10:19 INFO - PROCESS | 20686 | 23:10:22 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:10:23 INFO - PROCESS | 20686 | RSS: Main: 223571968 23:10:23 INFO - PROCESS | 20686 | 23:10:26 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:10:26 INFO - PROCESS | 20686 | RSS: Main: 221769728 23:10:26 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | Cycle 1(1): loaded http://localhost:54419/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54419/tests/kraken/driver.html?testName=ai-astar) 23:10:28 INFO - PROCESS | 20686 | RSS: Main: 272359424 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | __start_tp_report 23:10:28 INFO - PROCESS | 20686 | _x_x_mozilla_page_load 23:10:28 INFO - PROCESS | 20686 | _x_x_mozilla_page_load_details 23:10:28 INFO - PROCESS | 20686 | |i|pagename|runs| 23:10:28 INFO - PROCESS | 20686 | |0;ai-astar;115;116;133;113;113;115;115;114;115;114 23:10:28 INFO - PROCESS | 20686 | |1;audio-beat-detection;148;127;151;182;125;153;169;126;195;124 23:10:28 INFO - PROCESS | 20686 | |2;audio-dft;162;161;163;161;160;161;162;161;160;160 23:10:28 INFO - PROCESS | 20686 | |3;audio-fft;75;75;74;170;77;78;76;78;157;76 23:10:28 INFO - PROCESS | 20686 | |4;audio-oscillator;83;83;140;84;84;84;144;83;83;138 23:10:28 INFO - PROCESS | 20686 | |5;imaging-gaussian-blur;116;117;117;117;116;119;117;117;117;117 23:10:28 INFO - PROCESS | 20686 | |6;imaging-darkroom;146;148;146;146;147;146;146;146;146;146 23:10:28 INFO - PROCESS | 20686 | |7;imaging-desaturate;94;95;94;95;95;95;95;95;95;95 23:10:28 INFO - PROCESS | 20686 | |8;json-parse-financial;66;66;65;66;66;66;65;65;67;67 23:10:28 INFO - PROCESS | 20686 | |9;json-stringify-tinderbox;65;66;64;65;66;65;66;66;66;65 23:10:28 INFO - PROCESS | 20686 | |10;stanford-crypto-aes;84;82;83;82;84;91;85;89;82;83 23:10:28 INFO - PROCESS | 20686 | |11;stanford-crypto-ccm;133;145;130;135;135;133;136;135;183;131 23:10:28 INFO - PROCESS | 20686 | |12;stanford-crypto-pbkdf2;180;179;183;180;175;178;175;176;183;180 23:10:28 INFO - PROCESS | 20686 | |13;stanford-crypto-sha256-iterative;71;69;72;69;69;70;74;69;70;70 23:10:28 INFO - PROCESS | 20686 | __end_tp_report 23:10:28 INFO - PROCESS | 20686 | __start_cc_report 23:10:28 INFO - PROCESS | 20686 | _x_x_mozilla_cycle_collect,1940 23:10:28 INFO - PROCESS | 20686 | __end_cc_report 23:10:28 INFO - PROCESS | 20686 | __startTimestamp1472796628798__endTimestamp 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | ------- Summary: start ------- 23:10:28 INFO - PROCESS | 20686 | Number of tests: 14 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#0] ai-astar Cycles:10 Average:116.30 Median:115.00 stddev:5.95 (5.2%) stddev-sans-first:6.29 23:10:28 INFO - PROCESS | 20686 | Values: 115.0 116.0 133.0 113.0 113.0 115.0 115.0 114.0 115.0 114.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#1] audio-beat-detection Cycles:10 Average:150.00 Median:152.00 stddev:25.45 (16.7%) stddev-sans-first:26.99 23:10:28 INFO - PROCESS | 20686 | Values: 148.0 127.0 151.0 182.0 125.0 153.0 169.0 126.0 195.0 124.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 23:10:28 INFO - PROCESS | 20686 | Values: 162.0 161.0 163.0 161.0 160.0 161.0 162.0 161.0 160.0 160.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#3] audio-fft Cycles:10 Average:93.60 Median:76.00 stddev:36.99 (48.7%) stddev-sans-first:38.62 23:10:28 INFO - PROCESS | 20686 | Values: 75.0 75.0 74.0 170.0 77.0 78.0 76.0 78.0 157.0 76.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#4] audio-oscillator Cycles:10 Average:100.60 Median:83.00 stddev:27.69 (33.4%) stddev-sans-first:28.63 23:10:28 INFO - PROCESS | 20686 | Values: 83.0 83.0 140.0 84.0 84.0 84.0 144.0 83.0 83.0 138.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 23:10:28 INFO - PROCESS | 20686 | Values: 116.0 117.0 117.0 117.0 116.0 119.0 117.0 117.0 117.0 117.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 23:10:28 INFO - PROCESS | 20686 | Values: 146.0 148.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 23:10:28 INFO - PROCESS | 20686 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 23:10:28 INFO - PROCESS | 20686 | Values: 66.0 66.0 65.0 66.0 66.0 66.0 65.0 65.0 67.0 67.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 23:10:28 INFO - PROCESS | 20686 | Values: 65.0 66.0 64.0 65.0 66.0 65.0 66.0 66.0 66.0 65.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.00 stddev:3.10 (3.7%) stddev-sans-first:3.28 23:10:28 INFO - PROCESS | 20686 | Values: 84.0 82.0 83.0 82.0 84.0 91.0 85.0 89.0 82.0 83.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:135.00 stddev:15.78 (11.7%) stddev-sans-first:16.56 23:10:28 INFO - PROCESS | 20686 | Values: 133.0 145.0 130.0 135.0 135.0 133.0 136.0 135.0 183.0 131.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:180.00 stddev:2.92 (1.6%) stddev-sans-first:3.07 23:10:28 INFO - PROCESS | 20686 | Values: 180.0 179.0 183.0 180.0 175.0 178.0 175.0 176.0 183.0 180.0 23:10:28 INFO - PROCESS | 20686 | 23:10:28 INFO - PROCESS | 20686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:1.64 (2.3%) stddev-sans-first:1.72 23:10:28 INFO - PROCESS | 20686 | Values: 71.0 69.0 72.0 69.0 69.0 70.0 74.0 69.0 70.0 70.0 23:10:28 INFO - PROCESS | 20686 | -------- Summary: end -------- 23:10:28 INFO - PROCESS | 20686 | 23:10:30 INFO - TEST-INFO | 20686: exit 0 23:10:30 INFO - TEST-OK | kraken | took 64831ms 23:10:30 INFO - SUITE-END | took 1186s 23:10:30 INFO - Completed test suite (00:19:46) 23:10:30 INFO - javascript benchmark 23:10:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2582.548054090534, 2598.0469990473166, 2615.7907367410608, 2659.375046863988, 2669.2392668089597, 4612.10064037512, 4650.627949764019, 4680.719156850591, 4711.434627844659, 4789.0182512584925, 4689.742064186468, 4756.26277926921, 4774.4270687517455, 4778.80884764609, 4844.806207751686, 4343.565643435647, 4345.044090300129, 4429.313456414261, 4498.640108791289, 4519.118771839492, 4419.646428285745, 4444.999875028109, 4458.041521072969, 4473.373727678128, 4488.798004089807, 4231.090315582134, 4312.482502099737, 4353.259945809188, 4363.149185908749, 4393.51671316155, 4362.8909277268, 4369.847055353048, 4374.846880359172, 4380.759058251827, 4427.579379958899, 4362.040351122748, 4375.2124617568825, 4378.496472905625, 4414.3157810539415, 4431.800568974404, 4589.0821835633005, 4756.809727610926, 4838.322634831126, 4842.975785121087, 4916.557509824097, 5158.793648254103, 5172.534471897509, 5190.636655434121, 5206.453322401169, 5208.505192006791, 5219.6085293602755, 5224.843254702365, 5228.032813929435, 5232.92150617741, 5283.498067683564, 4631.8147274109, 4694.389729335165, 4729.219678753036, 4742.027884283713, 4810.2063159578975, 5224.869378265574, 5228.764705588258, 5229.607779416559, 5229.973850130725, 5231.686098834082, 4998.725070121105, 5002.424721157041, 5006.574441172541, 5010.298558201855, 5056.84829455117, 4895.951040489549, 5022.447530771612, 5064.037832811754, 5067.037262920034, 5143.8456846294675, 5021.6735912165595, 5035.244713292962, 5038.722870242099, 5041.949580504222, 5110.514501122388, 5037.244413338029, 5040.36995375578, 5041.949580504148, 5043.344365232496, 5044.041632089891, 4206.242876282237, 4240.342746874239, 4245.002424430257, 4293.570642935744, 4351.586599273064, 3821.7133714970932, 3842.9231415372096, 3941.881743547698, 3945.467361906106, 3949.7827619481204, 4475.948863353689, 4482.731036137842, 4484.192845287879, 4535.024969631545, 4542.500324964251, 4921.409430868319, 5144.202648589475, 5170.586353091744, 5180.48195180479, 5183.97408012965, 4367.978160109243, 4390.604845563915, 4426.468823741172, 4473.821047158142, 4477.059817438374, 4540.387047748578, 4575.794089265991, 4587.0, 4589.701669391479, 4640.187967105743, 4792.664513484023, 4803.135435621622, 4806.615470762331, 4812.927806082982, 4862.246351815475, 4866.1727506323305, 5062.696238225718, 5073.2897394365555, 5116.872078198075, 5125.79496820123, 4861.829835955725, 4863.732494712684, 4867.659263851497, 4911.238757992517, 4927.211646136601, 5046.646734728533, 5049.899002020013, 5051.722155281494, 5054.29239906406, 5062.493750624908, 4903.166461701448, 5047.924281135713, 5133.255677926605, 5147.6911385317, 5185.792568297376, 5021.472745361684, 5031.119554078095, 5032.798688052437, 5034.395872495324, 5061.797528098835, 5016.824411145592, 5020.221865610836, 5023.723695196799, 5024.296598476143, 5079.034983353151, 4741.739204343657, 4820.0, 4832.299316599003, 4867.0, 4926.959292773351, 5014.648974571745, 5014.924776128434, 5015.774290156879, 5016.824411145592, 5075.0357432087785, 4650.255959046536, 4892.706437613754, 4902.558769710743, 4908.239222920453, 4958.900821983612], "value": 4691.447842644629, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2697733412566, 571.3201290464347, 577.474498206632, 583.9474447299742, 587.2102022779361, 27123.32191695206, 37859.62140378595, 39391.40912886305, 39524.407133893015, 42907.35638965418, 39155.60844391555, 39393.8030309848, 39461.80269098658, 39484.210315793665, 39487.40768888462, 7681.5775132367835, 7838.804029899241, 7841.803954901116, 7842.490238134503, 7843.137254901956, 7674.616269186518, 7735.458517903721, 7754.767356979271, 7760.534367937914, 7763.611819409035, 7743.457957942946, 7746.225377462237, 7798.80502987427, 7803.0, 7806.258405451473, 7710.0, 7728.111267204231, 7758.185390533969, 7758.7672369829, 7761.22387761225, 7664.3102120808835, 7730.497517661334, 7730.536167829948, 7733.729319473791, 7738.4969976951315, 37411.0, 37631.4355284671, 37672.24655506875, 37673.81163094167, 37709.81145094257, 2465.4083020075054, 2552.412945022655, 2581.354661334666, 2619.9607005894927, 2637.643918071055, 27542.862285688443, 27856.582151267747, 28072.298192545146, 28135.437291254264, 28221.435571288665, 3230.144011836865, 3326.9334613307597, 3340.1816554944194, 3354.8490317935753, 3358.865645374182, 241455.0, 242976.0, 244355.0, 245203.0, 246428.0, 49751.75124124356, 50552.24171637429, 50597.49402506001, 50601.0, 50634.0, 31671.84164079165, 32687.0, 32783.50824737631, 46248.3062754059, 46434.303485447745, 14282.857171428153, 14372.784408233885, 14439.783403248959, 14451.92774036123, 14577.0, 10756.677299681021, 10814.513346899408, 10827.187960902964, 10827.67516974492, 10830.945845270724, 17314.480565583053, 17319.22063507145, 17357.47927562175, 17388.130593470276, 17402.39091631787, 6467.318497190764, 6617.187695229508, 6663.566868153554, 6668.0, 6717.5893062457435, 783.9960800195962, 865.7489328094782, 923.9676611318572, 925.7546750111226, 930.0513476254318, 27575.862120689668, 27701.168964931083, 27774.44451111007, 28067.157985260474, 28254.0, 906.8866391701038, 946.4936259101486, 947.0860619502214, 951.4671783801093, 1048.8112139814755, 14201.502947396792, 14844.925775371054, 14858.252233865103, 14901.403943842124, 14957.551273461813, 389844.0, 393083.89909311605, 393201.0, 393558.0, 395792.0, 47862.76068619704, 48115.75942120267, 48606.0, 48703.0, 48742.75628621905, 1055.5144633468537, 1058.8941105889503, 1128.7742451509566, 1163.4822503985645, 1171.1684703860356, 25482.0, 28516.146450480654, 28984.275393115342, 29261.414771704873, 29326.266843328234, 39917.2016559673, 40305.79847100746, 40365.79817100896, 40383.79808100941, 40406.39390409205, 302356.0, 302728.0, 303223.0, 303649.0, 304730.0, 21996.780032199473, 24332.51334973255, 24831.379215519755, 24959.12643057484, 25264.115755948453, 39778.60221397749, 40157.196856063296, 40519.59480405158, 40985.44232922718, 41615.37576936288, 34882.94197341422, 35093.0, 36221.09447263734, 36230.27539449144, 36698.26603467969, 32501.512477313332, 34770.130746731535, 35013.649863501036, 35025.82487087548, 35285.64714352824, 34336.65663343335, 34682.0, 34770.53951197631, 35041.824790875886, 35414.82292588521, 30337.848310758305, 32748.672513274563, 32969.67030329666, 33006.0, 33085.503717443775, 24483.63274550919, 24667.136650217155, 24723.8763806187, 24854.627180592666, 25327.746722533277], "value": 16896.744705371853, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.15301898273304, 356.2660918507874, 368.24693501788846, 370.3223101723845, 383.21823480100596, 12409.875901240985, 14393.064450810703, 16110.11394373309, 16204.189790510456, 16327.591810204765, 15508.5347439577, 15906.0, 16109.758353624677, 16194.35222591098, 16821.915890420532, 4309.418228539154, 4620.445546534404, 4651.348811166439, 4652.092841895832, 4657.836975705851, 3382.5433566468582, 4570.97714511427, 4640.048789998043, 4640.582347588716, 5044.722540260284, 5289.867753306179, 5373.194020896877, 5376.38171610264, 5379.273798037274, 5380.031594313042, 5115.104856650071, 5320.228566857822, 5348.839534813942, 5361.490658387447, 5367.704776237305, 5191.221316802472, 5208.401033881114, 5210.322658054429, 5210.348706411699, 5214.322138122057, 12501.56244531437, 15976.840231597651, 20165.0925708342, 20436.693449598268, 20542.38372848817, 1772.5745821002995, 1788.8121747216485, 1797.613513094678, 2032.7763945965933, 2222.733272007353, 9253.074692530761, 9284.721458356193, 9287.535623218879, 9747.0, 9867.434467038549, 1625.5285967069535, 1705.5650809043739, 1721.4835549335148, 1779.8487128593956, 1803.0804289812158, 64112.07210624271, 70533.29466705318, 71259.28740712578, 71794.0, 74696.25303747001, 9732.513374331254, 9750.756231094138, 10244.951009798071, 10250.333728307785, 10543.367397956146, 14117.152970821784, 14232.644183895278, 14244.786328205086, 14257.287135643384, 14261.78607320891, 6767.154105736783, 6991.720331186695, 7051.435885129177, 7213.314735100157, 7272.636368181676, 6589.53873228879, 6591.736330546821, 6729.1252137221845, 6784.830379240559, 6800.523963322619, 4333.804978775899, 4343.609075183185, 4348.456442944632, 4458.398116254329, 4465.330200469955, 1704.7613334133232, 1736.2273788164146, 1760.5334586334632, 1762.5946032412617, 1799.6220793633483, 802.5104686141449, 805.2551389964377, 836.0886633569438, 874.6239117179674, 884.2395539835736, 16155.0, 16370.181490925406, 17230.569235768955, 17376.21807018687, 17504.91247543754, 844.8774927635459, 848.8896443462311, 849.1169184048521, 869.4088020146235, 887.2281115429618, 8953.418027828171, 9139.862902056475, 9760.316777825485, 9833.311668183302, 10083.697489075339, 356447.0, 359296.0, 365126.0, 365142.0, 367022.0, 76165.0, 76900.23099769042, 77337.61331193308, 77451.0, 79013.0, 906.6237511432681, 919.3794188922451, 924.8890133184061, 950.8431108867098, 951.0632027452954, 14955.476558320406, 15010.099394036393, 15445.227738612799, 15700.92149539245, 15709.293081811118, 36074.4588831173, 36288.81855590705, 37454.62545374512, 37511.43732843955, 37711.24577508489, 46311.30533042074, 48108.759456203894, 48260.27609585789, 48924.510754892, 49373.50626493689, 6629.8397780389205, 7243.674034668347, 7298.0, 7373.18894921569, 7434.293742094493, 10364.222683298633, 10674.199435042248, 10886.129109671048, 10987.945060274647, 11026.228164028762, 9199.494027828534, 9247.445153290824, 9383.718488445085, 9425.622975081194, 9930.006999300012, 8202.974628171478, 8975.730728078168, 9506.239500840054, 9786.706398808046, 9832.705018849447, 9466.952665236906, 9570.61717531291, 9592.424454532751, 9647.855282170913, 9818.85271720939, 6989.825254368681, 7164.319389657974, 7250.093738282715, 7302.087239095114, 7314.305141011595, 8078.959605201936, 9245.121713437213, 9490.575041756178, 9554.808903822024, 9902.108810207115], "value": 7896.851755160937, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.3435696259457, 1363.1071648070515, 1399.5731301952903, 1475.8081449411575, 1707.2317457144281, 7493.475456718025, 7867.331276841468, 7936.563489008102, 8158.592070396486, 8166.101728809841, 8035.959820200906, 8115.1073381927945, 8123.75628731139, 8134.95932520338, 8137.064237612676, 2222.944426389341, 2231.040652519416, 2232.218723446797, 2233.542123864604, 2240.7759224077627, 2193.5941850757663, 2239.1155493580027, 2239.1155493580027, 2241.674957131215, 2241.9551608967813, 2146.7548821683463, 2164.4588852786806, 2193.3748881568754, 2201.0535469748, 2205.0848897707433, 2141.518158414352, 2152.709384233124, 2167.0681606909025, 2169.1973969631335, 2174.5759576882515, 2116.534362440261, 2143.132031527234, 2155.8814265215406, 2158.438805910459, 2165.4694599822988, 8042.839143217161, 8096.554689492075, 8101.95949020257, 8106.310963568104, 8108.2297181767635, 4377.562243775597, 4553.362529245908, 4565.292379681155, 4588.151192029485, 4601.056783359403, 2842.5878247653973, 3003.564483149953, 3037.787354885159, 3052.9542056869163, 3056.006797790727, 921.6083164654995, 929.5027160469117, 945.6264775413673, 959.1799011844912, 970.45169479244, 10924.399158046304, 10925.07136893365, 10952.726181845437, 10970.835437468364, 10975.560977560888, 4028.879133625996, 4038.6970977176243, 4110.568390319033, 4111.691623128278, 4141.337386018223, 8715.99766026915, 8736.606852691644, 8745.0, 8748.081451447633, 8748.606312715943, 4436.6007059364165, 4437.689361744711, 4444.6666500012625, 4452.998075433002, 4456.532064133284, 3473.149078475739, 3764.8117594120185, 3873.593272706325, 3879.321118804198, 4124.628783409507, 3425.092350527112, 3433.828308584561, 3551.9644803551632, 3576.8390422431053, 3598.4962105305276, 1030.314840630987, 1051.3639248254697, 1054.852320675106, 1055.1242468750918, 1061.5010944855906, 679.7756740275697, 735.4778107543597, 742.2392048150581, 779.918108598589, 842.5281842168405, 5046.116929537389, 5071.239314102914, 5110.361204849394, 5242.0, 5409.188621706697, 394.4635295997441, 406.4249087541155, 409.1836785612722, 410.0200520755397, 419.25372836351346, 3376.7805092668896, 3377.966220337765, 3379.7803142795638, 3388.6950174484414, 3480.182157193108, 9734.805303893883, 10123.0, 10166.847497287547, 10174.847377289347, 10193.23550733698, 10100.595976160872, 10102.848457273147, 10113.494325283853, 10131.746706332253, 10143.08712215904, 7573.35626471745, 7615.048118985127, 7635.961820190752, 7642.770716878503, 7645.350145237606, 5028.321176641107, 5121.820736274212, 5175.81884634036, 5177.663451875616, 5399.244105825266, 9142.817143657223, 9198.908010920073, 9222.44665320056, 9269.119433653792, 9277.721668349961, 9336.95331523338, 9405.764855878659, 9465.195458385839, 9468.715938521855, 9536.380135291183, 5795.971020144872, 6201.658908759881, 6335.081413195153, 6527.0, 6614.0, 7732.9226707732205, 7799.376049916106, 7807.765767026998, 7810.140884502586, 7838.960805195938, 6806.625635589892, 7003.824904377431, 7053.541519801196, 7065.6820443081215, 7165.319294666998, 6652.135222421054, 6797.1843378794865, 6841.3842754152365, 6889.104416425833, 6904.654767261516, 6267.185265915402, 6313.494920406449, 6331.556791024584, 6354.3963323484195, 6373.6175829450385, 4409.294512877924, 4416.8012439440945, 4422.77886105687, 4605.861824145281, 4687.531246875285, 4522.728636281834, 4551.681382303207, 4557.384753058427, 4640.489546149988, 4646.62826973848], "value": 4043.9606404075976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.79851204900547, 293.5977710536565, 294.079531067758, 296.3347285344402, 304.7394477721548, 4834.009028149229, 4855.902881942359, 4870.878228044301, 4885.828995985141, 4906.705597664142, 4755.8811029724275, 4774.73738944358, 4835.742706896206, 4837.492063333352, 4840.588549973248, 3609.2420591675777, 3719.479272901796, 3723.4042553191493, 3731.1977924746175, 3763.7177211709095, 3699.260147970403, 3778.2821263959895, 3801.3157631626295, 3814.809259537012, 3851.4993050903345, 3775.4903088083033, 3775.8678446254385, 3776.9811150944206, 3793.7806383265684, 3801.1257410795533, 3537.5224344713392, 3731.0, 3778.9811050944845, 3807.1053302473897, 3814.694824414054, 3280.1471617379652, 3281.9343613127844, 3287.227501537137, 3291.572095627553, 3303.884364047247, 4643.512431194744, 4644.397682799428, 4651.860294227936, 4655.766221951152, 4661.3241080043545, 4225.260579398624, 4269.188854117708, 4279.229738647043, 4279.700420970534, 4289.957100428987, 4894.241392584155, 4902.852914412573, 4905.650945989845, 4913.680610760289, 4933.901321973541, 3545.450455179451, 3547.5455063423874, 3553.271579326258, 3574.249407624402, 3589.335972845032, 5189.6886186828915, 5199.272101905736, 5226.8693282667855, 5267.868303292448, 5282.498162674578, 3562.5012498250267, 3633.8916630427443, 3641.126129728899, 3675.4303083022173, 3680.595134535199, 3694.743787112395, 3709.3879509880567, 3711.2763011212555, 3712.3132220539287, 3713.7400381973544, 4185.686073544496, 4214.241436541452, 4236.512801027921, 4245.087306229114, 4246.278132717445, 4956.950430495721, 4961.528654777862, 4976.303317535548, 4994.500549945049, 4998.850034498971, 1209.9939500302619, 1282.6152154353656, 1295.1710905020607, 1298.69480672042, 1298.9608313349283, 787.799111226645, 827.6937533112666, 830.9916900830914, 840.9369297302727, 843.7173546861748, 4311.744632802277, 4315.8273669053515, 4447.844325448658, 4470.0389416275, 4485.237509623372, 5138.332016837787, 5146.073706732749, 5161.612879034087, 5183.015227106837, 5207.427183009866, 4457.977710111429, 4465.977670111629, 4482.932756008662, 4497.370368148462, 4523.095380923828, 2617.738226177405, 2697.6223328733995, 2720.5375086235385, 2721.414895797413, 2721.6053672217813, 4855.174620314555, 4861.222204447272, 4862.905846184651, 4863.975680121647, 4903.0, 5074.797008119634, 5255.816046438356, 5275.683458992396, 5283.365996080495, 5292.179712144623, 4808.61531077513, 4840.540148685799, 4851.151048566487, 4860.639021074105, 4871.926921096116, 5117.667351622133, 5122.718250496258, 5130.307408499804, 5145.459726728789, 5168.689878607295, 4903.803847846046, 5031.547160755549, 5054.545090941833, 5065.036953382312, 5088.771005304696, 4869.514797986506, 4881.219004959189, 4888.291197776373, 4903.577962390867, 4906.460289368238, 3691.298653255873, 3694.0, 3698.556173259226, 3718.4794128821445, 3727.1427571659215, 4945.2582112683385, 5198.792048318025, 5208.192730126836, 5223.634345595924, 5229.346331708536, 4978.726170060537, 4980.5019498049905, 4984.800607975641, 4992.950070499249, 5009.918899876477, 4924.507549245047, 4942.209246520683, 4982.10322142018, 4983.6760610560195, 4990.900181996412, 4667.83662571822, 4842.661013729005, 4844.273358996179, 4848.393950756155, 4879.756012199447, 4821.566059054561, 4893.559579637849, 5052.747362631927, 5054.21659642756, 5069.188929771219, 4876.268924531741, 4912.413937825336, 4949.505049495022, 4950.217921548167, 4965.553100220997], "value": 3776.0011568992186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.8800983808158, 619.2746770076964, 664.3721683009558, 683.9952080568276, 688.1741909708351, 12069.577564785235, 12826.230426174425, 12854.742905141891, 12858.292793896331, 12914.289714065735, 11895.940520297387, 11978.580749673763, 12002.279863208214, 12004.879951200508, 12008.159428839985, 597.8595030167907, 735.4006484714954, 759.7948553890446, 761.9885701714479, 763.9388848892103, 722.2308241722549, 747.2490147401853, 747.4580928826584, 752.7026824404358, 753.8303881626617, 797.9122296547375, 799.3684988858795, 799.5842162075717, 799.6241766369826, 802.3100133884855, 606.205870309891, 791.4024911192071, 792.742358733411, 792.9682812687486, 793.4287313134563, 563.8111232736996, 581.2066529187671, 582.8426324892255, 584.7485581200082, 585.1281590430228, 11450.599229026944, 11480.196386252883, 11507.654770356903, 11527.423628818526, 11529.077673786162, 12033.51865925362, 12629.621111366674, 12806.0, 12991.675208119872, 13198.406071726795, 9406.153446189808, 11860.88139118615, 11909.583164589285, 11943.820842687366, 12113.273203607723, 3939.862104826317, 3978.9801050994847, 4041.7574945503366, 4091.672666186728, 4099.282625540519, 56159.0, 57130.0, 57209.427905720826, 57265.713671431375, 57370.713146434, 8970.596323165473, 9099.863502047474, 9135.177833994972, 9309.860352094724, 9400.388974716621, 17359.21883515245, 17450.21474033646, 17462.30150793954, 17482.125893705517, 17589.29642814299, 6126.663033533109, 6187.731515039496, 6309.0, 6449.419552240321, 6463.8060858173385, 3322.80063196209, 3482.9651703483146, 3512.473129030666, 3565.750397472204, 3593.6945359644724, 2787.080263513036, 2936.985315073411, 2942.6027486289504, 2944.20506463258, 2945.3078526546246, 515.9742012899341, 528.4055437632663, 539.2477493895993, 542.1244689825982, 547.5756288876073, 492.0527983631495, 516.8423630792645, 520.6563667979116, 561.2339157050596, 565.1155940952397, 7682.73110441143, 7686.040059784719, 7718.266764657348, 7977.0, 8078.272955434039, 453.20688794609435, 515.8813472901254, 532.052945756554, 533.2747463449705, 577.5177726598338, 3554.20030493137, 3591.748577599543, 3611.2416392557334, 3628.455731640275, 3686.09690625793, 58339.70830145907, 58671.773052482524, 59271.11093333604, 59814.401855981756, 65638.34361656418, 82626.0, 83751.5812420934, 88787.0, 89443.0, 89964.10035899557, 47705.28442073372, 70116.94824577635, 70527.0, 73162.0, 73860.63069684617, 6635.4359879410795, 7057.611831349325, 7068.29317068289, 7090.929090709027, 7161.460286038527, 30055.549166762077, 37472.81263593664, 37619.62380376161, 37663.435048473744, 39315.60684393119, 38592.0, 46255.53744462512, 50258.748706256236, 56853.71573142108, 59637.0, 10471.476426178813, 11068.501917413896, 11199.60801371948, 11354.943225283821, 11425.714357141138, 12287.0, 12461.626151215478, 12466.314352710688, 12556.811647825107, 13257.602271931857, 9452.905470945203, 10892.346459212473, 11285.43572821372, 11557.595484158013, 11793.528258869892, 10639.734006649896, 10783.299085559414, 11363.261388009752, 11654.825177622512, 12284.078694098158, 11258.549658013588, 11334.773304533699, 11630.360330181922, 11701.356425396345, 12241.0, 9663.806723865342, 9701.73877395934, 10177.542010609392, 10517.579296828042, 10896.400697961688, 9214.631414743335, 9736.269779766404, 9895.939502717027, 10027.298089133983, 10039.548220329956], "value": 6174.3506217641, "unit": "score"}], "name": "dromaeo_css", "value": 6239.844608192637, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 133.0, 113.0, 113.0, 115.0, 115.0, 114.0, 115.0, 114.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 127.0, 151.0, 182.0, 125.0, 153.0, 169.0, 126.0, 195.0, 124.0], "value": 150.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 160.0, 161.0, 162.0, 161.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 170.0, 77.0, 78.0, 76.0, 78.0, 157.0, 76.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 140.0, 84.0, 84.0, 84.0, 144.0, 83.0, 83.0, 138.0], "value": 100.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 119.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 67.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 83.0, 82.0, 84.0, 91.0, 85.0, 89.0, 82.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 145.0, 130.0, 135.0, 135.0, 133.0, 136.0, 135.0, 183.0, 131.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 183.0, 180.0, 175.0, 178.0, 175.0, 176.0, 183.0, 180.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 72.0, 69.0, 69.0, 70.0, 74.0, 69.0, 70.0, 70.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1584.3000000000002, "alertThreshold": 2.0}]} 23:10:30 INFO - Return code: 0 23:10:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:10:30 INFO - # TBPL SUCCESS # 23:10:30 INFO - Running post-action listener: _resource_record_post_action 23:10:30 INFO - [mozharness: 2016-09-02 06:10:30.592428Z] Finished run-tests step (success) 23:10:30 INFO - Running post-run listener: _resource_record_post_run 23:10:30 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 12288; Write bytes: 416608256; Read time: 116; Write time: 316500 23:10:30 INFO - TinderboxPrint: CPU usage
13.0% 23:10:30 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 116 23:10:30 INFO - TinderboxPrint: I/O write bytes / time
416,608,256 / 316,500 23:10:30 INFO - TinderboxPrint: CPU idle
8,346.7 (86.9%) 23:10:30 INFO - TinderboxPrint: CPU user
1,205.0 (12.6%) 23:10:30 INFO - TinderboxPrint: Swap in / out
0 / 0 23:10:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 113872896; Read time: 0; Write time: 114444 23:10:30 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 290881536; Read time: 108; Write time: 198776 23:10:31 INFO - Running post-run listener: _upload_blobber_files 23:10:31 INFO - Blob upload gear active. 23:10:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:10:31 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 23:10:31 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:10:31 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:10:31 INFO - (blobuploader) - INFO - Open directory for files ... 23:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:10:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:10:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:10:32 INFO - (blobuploader) - INFO - Done attempting. 23:10:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:10:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:10:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:10:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:10:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:10:36 INFO - (blobuploader) - INFO - Done attempting. 23:10:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:10:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:10:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:10:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:10:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:10:37 INFO - (blobuploader) - INFO - Done attempting. 23:10:37 INFO - (blobuploader) - INFO - Iteration through files over. 23:10:37 INFO - Return code: 0 23:10:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:10:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:10:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/09a05a61d55d7b06ccda84ebbe8678eff8928145a3d08dcf162503bb52d7700e40413c06ff7a4ae1fde25a8fda9e4fd8377c0b68632b0223d327e8355086083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5eb31622536aa714db4f55186c37cda681dad7ddf28f781832aff326c204c01e9580ee1d7d2feea0281d0766a072939fde5b20bf00fddb63ce462958e28f1115", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1299a360f63e927d15cb6fbd17639d38d0d90c25a1dcb17108f9b5ea9b3db5dd441b59819ccad8fb9bedb1f9855514c037443c8dc2893ddef8db23d20970911a"} 23:10:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:10:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:10:37 INFO - Contents: 23:10:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/09a05a61d55d7b06ccda84ebbe8678eff8928145a3d08dcf162503bb52d7700e40413c06ff7a4ae1fde25a8fda9e4fd8377c0b68632b0223d327e8355086083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5eb31622536aa714db4f55186c37cda681dad7ddf28f781832aff326c204c01e9580ee1d7d2feea0281d0766a072939fde5b20bf00fddb63ce462958e28f1115", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1299a360f63e927d15cb6fbd17639d38d0d90c25a1dcb17108f9b5ea9b3db5dd441b59819ccad8fb9bedb1f9855514c037443c8dc2893ddef8db23d20970911a"} 23:10:37 INFO - Running post-run listener: copy_logs_to_upload_dir 23:10:37 INFO - Copying logs to upload dir... 23:10:37 INFO - mkdir: /builds/slave/test/build/upload/logs 23:10:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.107243 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-09-01 23:10:36.924047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:10:36.928108) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/09a05a61d55d7b06ccda84ebbe8678eff8928145a3d08dcf162503bb52d7700e40413c06ff7a4ae1fde25a8fda9e4fd8377c0b68632b0223d327e8355086083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5eb31622536aa714db4f55186c37cda681dad7ddf28f781832aff326c204c01e9580ee1d7d2feea0281d0766a072939fde5b20bf00fddb63ce462958e28f1115", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1299a360f63e927d15cb6fbd17639d38d0d90c25a1dcb17108f9b5ea9b3db5dd441b59819ccad8fb9bedb1f9855514c037443c8dc2893ddef8db23d20970911a"} build_url:https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/A5_6SaSoSq2Q1fAG-rjeFg/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/Fx-Team-Non-PGO/sha512/09a05a61d55d7b06ccda84ebbe8678eff8928145a3d08dcf162503bb52d7700e40413c06ff7a4ae1fde25a8fda9e4fd8377c0b68632b0223d327e8355086083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5eb31622536aa714db4f55186c37cda681dad7ddf28f781832aff326c204c01e9580ee1d7d2feea0281d0766a072939fde5b20bf00fddb63ce462958e28f1115", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1299a360f63e927d15cb6fbd17639d38d0d90c25a1dcb17108f9b5ea9b3db5dd441b59819ccad8fb9bedb1f9855514c037443c8dc2893ddef8db23d20970911a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:10:36.955025) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:10:36.955347) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472795388.982556-36698963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:10:37.042469) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:10:37.043050) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:10:37.043778) ========= ========= Total master_lag: 0.18 =========