builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1468494729.22 results: success (0) buildid: 20160714030744 builduid: 85678dd052cf465a9c6840c2d40bb34f revision: 49ef0b8c3cfa522e35ef2fb937da43cecba6b99c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.216479) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.216898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.217182) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.237282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.237650) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-14 04:12:09-- 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% 12.6M=0.001s 2016-07-14 04:12:09 (12.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532791 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.784874) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.785944) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.089578 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:09.887166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-14 04:12:09.887511) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 49ef0b8c3cfa522e35ef2fb937da43cecba6b99c --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 49ef0b8c3cfa522e35ef2fb937da43cecba6b99c --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=20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-14 04:12:09,887 truncating revision to first 12 chars 2016-07-14 04:12:09,887 Setting DEBUG logging. 2016-07-14 04:12:09,887 attempt 1/10 2016-07-14 04:12:09,887 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/49ef0b8c3cfa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 04:12:10,829 unpacking tar archive at: fx-team-49ef0b8c3cfa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.272618 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-14 04:12:11.179468) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:11.179841) ========= script_repo_revision: 49ef0b8c3cfa522e35ef2fb937da43cecba6b99c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:11.180270) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:11.180533) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 04:12:11.190886) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-07-14 04:12:11.191263) ========= /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=20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:12:11 INFO - MultiFileLogger online at 20160714 04:12:11 in /builds/slave/test 04:12:11 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 04:12:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:12:11 INFO - {'append_to_log': False, 04:12:11 INFO - 'base_work_dir': '/builds/slave/test', 04:12:11 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:12:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:12:11 INFO - 'branch': 'Fx-Team-Non-PGO', 04:12:11 INFO - 'buildbot_json_path': 'buildprops.json', 04:12:11 INFO - 'config_files': ('talos/linux_config.py',), 04:12:11 INFO - 'default_actions': ('clobber', 04:12:11 INFO - 'read-buildbot-config', 04:12:11 INFO - 'download-and-extract', 04:12:11 INFO - 'populate-webroot', 04:12:11 INFO - 'create-virtualenv', 04:12:11 INFO - 'install', 04:12:11 INFO - 'run-tests'), 04:12:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:12:11 INFO - 'download_minidump_stackwalk': True, 04:12:11 INFO - 'download_symbols': 'ondemand', 04:12:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:12:11 INFO - 'tooltool.py': '/tools/tooltool.py', 04:12:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:12:11 INFO - '/tools/misc-python/virtualenv.py')}, 04:12:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:12:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:12:11 INFO - 'installer_path': 'installer.exe', 04:12:11 INFO - 'log_level': 'info', 04:12:11 INFO - 'log_name': 'talos', 04:12:11 INFO - 'log_to_console': True, 04:12:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:12:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:12:11 INFO - 'opt_config_files': (), 04:12:11 INFO - 'pip_index': False, 04:12:11 INFO - 'sps_profile': False, 04:12:11 INFO - 'sps_profile_interval': 0, 04:12:11 INFO - 'suite': 'dromaeojs', 04:12:11 INFO - 'system_bits': '32', 04:12:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:12:11 INFO - 'title': 'talos-linux64-ix-015', 04:12:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:12:11 INFO - 'use_talos_json': True, 04:12:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:12:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:12:11 INFO - 'work_dir': 'build'} 04:12:11 INFO - ##### 04:12:11 INFO - ##### Running clobber step. 04:12:11 INFO - ##### 04:12:11 INFO - Running pre-action listener: _resource_record_pre_action 04:12:11 INFO - Running main action method: clobber 04:12:11 INFO - rmtree: /builds/slave/test/build 04:12:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:12:13 INFO - Running post-action listener: _resource_record_post_action 04:12:13 INFO - ##### 04:12:13 INFO - ##### Finished clobber step (success) 04:12:13 INFO - ##### 04:12:13 INFO - ##### 04:12:13 INFO - ##### Running read-buildbot-config step. 04:12:13 INFO - ##### 04:12:13 INFO - Running pre-action listener: _resource_record_pre_action 04:12:13 INFO - Running main action method: read_buildbot_config 04:12:13 INFO - Using buildbot properties: 04:12:13 INFO - { 04:12:13 INFO - "project": "", 04:12:13 INFO - "product": "firefox", 04:12:13 INFO - "script_repo_revision": "production", 04:12:13 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 04:12:13 INFO - "repository": "", 04:12:13 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 04:12:13 INFO - "buildid": "20160714030744", 04:12:13 INFO - "pgo_build": "False", 04:12:13 INFO - "basedir": "/builds/slave/test", 04:12:13 INFO - "buildnumber": 3153, 04:12:13 INFO - "slavename": "talos-linux64-ix-015", 04:12:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:12:13 INFO - "platform": "ubuntu64_hw", 04:12:13 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:12:13 INFO - "branch": "fx-team", 04:12:13 INFO - "repo_path": "integration/fx-team", 04:12:13 INFO - "revision": "49ef0b8c3cfa522e35ef2fb937da43cecba6b99c", 04:12:13 INFO - "stage_platform": "linux64", 04:12:13 INFO - "builduid": "85678dd052cf465a9c6840c2d40bb34f", 04:12:13 INFO - "slavebuilddir": "test" 04:12:13 INFO - } 04:12:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:12:13 INFO - Running post-action listener: _resource_record_post_action 04:12:13 INFO - ##### 04:12:13 INFO - ##### Finished read-buildbot-config step (success) 04:12:13 INFO - ##### 04:12:13 INFO - ##### 04:12:13 INFO - ##### Running download-and-extract step. 04:12:13 INFO - ##### 04:12:13 INFO - Running pre-action listener: _resource_record_pre_action 04:12:13 INFO - Running main action method: download_and_extract 04:12:13 INFO - mkdir: /builds/slave/test/build/tests 04:12: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:13 INFO - https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:12:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:12:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:12:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:12:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:12:14 INFO - Downloaded 1361 bytes. 04:12:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:12:14 INFO - Using the following test package requirements: 04:12:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:12:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:12:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:12:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'jsshell-linux-x86_64.zip'], 04:12:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:12:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:12:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:12:14 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:12:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:12:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:12:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:12:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:14 INFO - https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:12:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:12:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:12:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:12:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:12:16 INFO - Downloaded 22755452 bytes. 04:12:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:12:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:12:17 INFO - Return code: 0 04:12:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:12:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:17 INFO - https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:12:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:12:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:12:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:12:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:12:23 INFO - Downloaded 22755452 bytes. 04:12:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:12:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:12:25 INFO - Return code: 0 04:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:25 INFO - https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:12:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:12:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:12:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:12:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:12:26 INFO - Downloaded 14403317 bytes. 04:12:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:12:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:12:26 INFO - Return code: 0 04:12: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:26 INFO - https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:12:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:12:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:12:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:12:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:12:27 INFO - retry: attempt #1 caught exception: '' 04:12:27 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #2 04:12:58 INFO - Downloaded 57054170 bytes. 04:12:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:12:58 INFO - mkdir: /builds/slave/test/properties 04:12:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:12:58 INFO - Writing to file /builds/slave/test/properties/build_url 04:12:58 INFO - Contents: 04:12:58 INFO - build_url:https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:12:59 INFO - Running post-action listener: _resource_record_post_action 04:12:59 INFO - Running post-action listener: set_extra_try_arguments 04:12:59 INFO - ##### 04:12:59 INFO - ##### Finished download-and-extract step (success) 04:12:59 INFO - ##### 04:12:59 INFO - ##### 04:12:59 INFO - ##### Running populate-webroot step. 04:12:59 INFO - ##### 04:12:59 INFO - Running pre-action listener: _resource_record_pre_action 04:12:59 INFO - Running main action method: populate_webroot 04:12:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:12:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:12:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:12:59 INFO - '--tppagecycles', 04:12:59 INFO - '7'], 04:12:59 INFO - 'tests': ['tsvgm']}}, 04:12:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:12:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:12:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:12:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:12:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:12:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:12:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['damp', 'tps']}, 04:12:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'tests': ['damp', 'tps']}, 04:12:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['dromaeo_dom']}, 04:12:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:12:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['basic_compositor_video']}, 04:12:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:12:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['a11yr', 04:12:59 INFO - 'ts_paint', 04:12:59 INFO - 'tpaint', 04:12:59 INFO - 'sessionrestore', 04:12:59 INFO - 'sessionrestore_no_auto_restore', 04:12:59 INFO - 'tabpaint']}, 04:12:59 INFO - 'other-e10s': {'tests': ['a11yr', 04:12:59 INFO - 'ts_paint', 04:12:59 INFO - 'tpaint', 04:12:59 INFO - 'sessionrestore', 04:12:59 INFO - 'sessionrestore_no_auto_restore', 04:12:59 INFO - 'tabpaint']}, 04:12:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:12:59 INFO - 'ts_paint', 04:12:59 INFO - 'tpaint', 04:12:59 INFO - 'sessionrestore', 04:12:59 INFO - 'sessionrestore_no_auto_restore', 04:12:59 INFO - 'tabpaint']}, 04:12:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:12:59 INFO - 'ts_paint', 04:12:59 INFO - 'tpaint', 04:12:59 INFO - 'sessionrestore', 04:12:59 INFO - 'sessionrestore_no_auto_restore', 04:12:59 INFO - 'tabpaint']}, 04:12:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['a11yr', 04:12:59 INFO - 'ts_paint', 04:12:59 INFO - 'tpaint', 04:12:59 INFO - 'sessionrestore', 04:12:59 INFO - 'sessionrestore_no_auto_restore', 04:12:59 INFO - 'tabpaint']}, 04:12:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['a11yr', 04:12:59 INFO - 'ts_paint', 04:12:59 INFO - 'tpaint', 04:12:59 INFO - 'sessionrestore', 04:12:59 INFO - 'sessionrestore_no_auto_restore', 04:12:59 INFO - 'tabpaint']}, 04:12:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['tsvgx', 04:12:59 INFO - 'tsvgr_opacity', 04:12:59 INFO - 'tart', 04:12:59 INFO - 'tscrollx', 04:12:59 INFO - 'cart']}, 04:12:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:12:59 INFO - 'tsvgr_opacity', 04:12:59 INFO - 'tart', 04:12:59 INFO - 'tscrollx', 04:12:59 INFO - 'cart']}, 04:12:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'talos_options': ['--disable-e10s'], 04:12:59 INFO - 'tests': ['tp5o']}, 04:12:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'tests': ['tp5o']}, 04:12:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'talos_options': ['--disable-e10s', 04:12:59 INFO - '--xperf_path', 04:12:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:12:59 INFO - 'tests': ['tp5n']}, 04:12:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:12:59 INFO - 'talos_options': ['--xperf_path', 04:12:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:12:59 INFO - 'tests': ['tp5n']}}, 04:12:59 INFO - 'talos.zip': {'path': '', 04:12:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:12:59 INFO - Running post-action listener: _resource_record_post_action 04:12:59 INFO - ##### 04:12:59 INFO - ##### Finished populate-webroot step (success) 04:12:59 INFO - ##### 04:12:59 INFO - ##### 04:12:59 INFO - ##### Running create-virtualenv step. 04:12:59 INFO - ##### 04:12:59 INFO - Running pre-action listener: _resource_record_pre_action 04:12:59 INFO - Running main action method: create_virtualenv 04:12:59 INFO - Creating virtualenv /builds/slave/test/build/venv 04:12:59 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 04:12:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:12:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:12:59 INFO - Using real prefix '/usr' 04:12:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:12:59 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:01 INFO - Installing pip.................done. 04:13:01 INFO - Return code: 0 04:13:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:01 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 04:13:01 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 04:13:01 INFO - Using env: {'DISPLAY': ':0', 04:13:01 INFO - 'HOME': '/home/cltbld', 04:13:01 INFO - 'LANG': 'en_US.UTF-8', 04:13:01 INFO - 'LANGUAGE': 'en_US:en', 04:13:01 INFO - 'LOGNAME': 'cltbld', 04:13:01 INFO - 'MAIL': '/var/mail/cltbld', 04:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:01 INFO - 'MOZ_NO_REMOTE': '1', 04:13:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:01 INFO - 'NO_EM_RESTART': '1', 04:13:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:01 INFO - 'PWD': '/builds/slave/test', 04:13:01 INFO - 'SHELL': '/bin/bash', 04:13:01 INFO - 'SHLVL': '1', 04:13:01 INFO - 'TERM': 'linux', 04:13:01 INFO - 'TMOUT': '86400', 04:13:01 INFO - 'USER': 'cltbld', 04:13:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:01 INFO - '_': '/tools/buildbot/bin/python'} 04:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:02 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 04:13:02 INFO - Cleaning up... 04:13:02 INFO - Return code: 0 04:13:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:02 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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:02 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 04:13:02 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 04:13:02 INFO - Using env: {'DISPLAY': ':0', 04:13:02 INFO - 'HOME': '/home/cltbld', 04:13:02 INFO - 'LANG': 'en_US.UTF-8', 04:13:02 INFO - 'LANGUAGE': 'en_US:en', 04:13:02 INFO - 'LOGNAME': 'cltbld', 04:13:02 INFO - 'MAIL': '/var/mail/cltbld', 04:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:02 INFO - 'MOZ_NO_REMOTE': '1', 04:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:02 INFO - 'NO_EM_RESTART': '1', 04:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:02 INFO - 'PWD': '/builds/slave/test', 04:13:02 INFO - 'SHELL': '/bin/bash', 04:13:02 INFO - 'SHLVL': '1', 04:13:02 INFO - 'TERM': 'linux', 04:13:02 INFO - 'TMOUT': '86400', 04:13:02 INFO - 'USER': 'cltbld', 04:13:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:02 INFO - '_': '/tools/buildbot/bin/python'} 04:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:02 INFO - Downloading/unpacking psutil>=3.1.1 04:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:02 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 04:13:02 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 04:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:04 INFO - Installing collected packages: psutil 04:13:04 INFO - Running setup.py install for psutil 04:13:04 INFO - building 'psutil._psutil_linux' extension 04:13:04 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 04:13:05 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 04:13:05 INFO - building 'psutil._psutil_posix' extension 04:13:05 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 04:13:05 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 04:13:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:05 INFO - Successfully installed psutil 04:13:05 INFO - Cleaning up... 04:13:05 INFO - Return code: 0 04:13:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13:05 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:05 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:06 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 04:13:06 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 04:13:06 INFO - Using env: {'DISPLAY': ':0', 04:13:06 INFO - 'HOME': '/home/cltbld', 04:13:06 INFO - 'LANG': 'en_US.UTF-8', 04:13:06 INFO - 'LANGUAGE': 'en_US:en', 04:13:06 INFO - 'LOGNAME': 'cltbld', 04:13:06 INFO - 'MAIL': '/var/mail/cltbld', 04:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:06 INFO - 'MOZ_NO_REMOTE': '1', 04:13:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:06 INFO - 'NO_EM_RESTART': '1', 04:13:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:06 INFO - 'PWD': '/builds/slave/test', 04:13:06 INFO - 'SHELL': '/bin/bash', 04:13:06 INFO - 'SHLVL': '1', 04:13:06 INFO - 'TERM': 'linux', 04:13:06 INFO - 'TMOUT': '86400', 04:13:06 INFO - 'USER': 'cltbld', 04:13:06 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:06 INFO - '_': '/tools/buildbot/bin/python'} 04:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:06 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 04:13:06 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 04:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:08 INFO - Installing collected packages: mozsystemmonitor 04:13:08 INFO - Running setup.py install for mozsystemmonitor 04:13:08 INFO - Successfully installed mozsystemmonitor 04:13:08 INFO - Cleaning up... 04:13:08 INFO - Return code: 0 04:13:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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', '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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:13: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 jsonschema==2.5.1 04:13:08 INFO - Using env: {'DISPLAY': ':0', 04:13:08 INFO - 'HOME': '/home/cltbld', 04:13:08 INFO - 'LANG': 'en_US.UTF-8', 04:13:08 INFO - 'LANGUAGE': 'en_US:en', 04:13:08 INFO - 'LOGNAME': 'cltbld', 04:13:08 INFO - 'MAIL': '/var/mail/cltbld', 04:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:08 INFO - 'MOZ_NO_REMOTE': '1', 04:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:08 INFO - 'NO_EM_RESTART': '1', 04:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:08 INFO - 'PWD': '/builds/slave/test', 04:13:08 INFO - 'SHELL': '/bin/bash', 04:13:08 INFO - 'SHLVL': '1', 04:13:08 INFO - 'TERM': 'linux', 04:13:08 INFO - 'TMOUT': '86400', 04:13:08 INFO - 'USER': 'cltbld', 04:13:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:08 INFO - '_': '/tools/buildbot/bin/python'} 04:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:08 INFO - Downloading/unpacking jsonschema==2.5.1 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13:10 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 04:13:10 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 04:13:10 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 04:13:10 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 04:13:10 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 04:13:10 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 04:13:10 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:10 INFO - warning: no files found matching '*.txt' 04:13:10 INFO - no previously-included directories found matching 'build' 04:13:10 INFO - no previously-included directories found matching 'dist' 04:13:10 INFO - no previously-included directories found matching '.git*' 04:13:10 INFO - Installing collected packages: jsonschema, functools32 04:13:10 INFO - Running setup.py install for functools32 04:13:10 INFO - warning: no files found matching '*.txt' 04:13:10 INFO - no previously-included directories found matching 'build' 04:13:10 INFO - no previously-included directories found matching 'dist' 04:13:10 INFO - no previously-included directories found matching '.git*' 04:13:10 INFO - Successfully installed jsonschema functools32 04:13:10 INFO - Cleaning up... 04:13:10 INFO - Return code: 0 04:13:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:10 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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:10 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 04:13:10 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 04:13:10 INFO - Using env: {'DISPLAY': ':0', 04:13:10 INFO - 'HOME': '/home/cltbld', 04:13:10 INFO - 'LANG': 'en_US.UTF-8', 04:13:10 INFO - 'LANGUAGE': 'en_US:en', 04:13:10 INFO - 'LOGNAME': 'cltbld', 04:13:10 INFO - 'MAIL': '/var/mail/cltbld', 04:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:10 INFO - 'MOZ_NO_REMOTE': '1', 04:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:10 INFO - 'NO_EM_RESTART': '1', 04:13:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:10 INFO - 'PWD': '/builds/slave/test', 04:13:10 INFO - 'SHELL': '/bin/bash', 04:13:10 INFO - 'SHLVL': '1', 04:13:10 INFO - 'TERM': 'linux', 04:13:10 INFO - 'TMOUT': '86400', 04:13:10 INFO - 'USER': 'cltbld', 04:13:10 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:10 INFO - '_': '/tools/buildbot/bin/python'} 04:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:12 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:13 INFO - Downloading docopt-0.6.1.tar.gz 04:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:13 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:13 INFO - Running setup.py install for blobuploader 04:13:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:13 INFO - Running setup.py install for requests 04:13:14 INFO - Running setup.py install for docopt 04:13:14 INFO - Successfully installed blobuploader requests docopt 04:13:14 INFO - Cleaning up... 04:13:14 INFO - Return code: 0 04:13:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:14 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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:14 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 04:13:14 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 04:13:14 INFO - Using env: {'DISPLAY': ':0', 04:13:14 INFO - 'HOME': '/home/cltbld', 04:13:14 INFO - 'LANG': 'en_US.UTF-8', 04:13:14 INFO - 'LANGUAGE': 'en_US:en', 04:13:14 INFO - 'LOGNAME': 'cltbld', 04:13:14 INFO - 'MAIL': '/var/mail/cltbld', 04:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:14 INFO - 'MOZ_NO_REMOTE': '1', 04:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:14 INFO - 'NO_EM_RESTART': '1', 04:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:14 INFO - 'PWD': '/builds/slave/test', 04:13:14 INFO - 'SHELL': '/bin/bash', 04:13:14 INFO - 'SHLVL': '1', 04:13:14 INFO - 'TERM': 'linux', 04:13:14 INFO - 'TMOUT': '86400', 04:13:14 INFO - 'USER': 'cltbld', 04:13:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:14 INFO - '_': '/tools/buildbot/bin/python'} 04:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:14 INFO - Running setup.py (path:/tmp/pip-JlWCpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:14 INFO - Running setup.py (path:/tmp/pip-UxIMrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:14 INFO - Running setup.py (path:/tmp/pip-qfyZM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:14 INFO - Running setup.py (path:/tmp/pip-3JcPya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:14 INFO - Running setup.py (path:/tmp/pip-k7E73t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:14 INFO - Running setup.py (path:/tmp/pip-v8padX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:15 INFO - Running setup.py (path:/tmp/pip-cw07SV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:15 INFO - Running setup.py (path:/tmp/pip-VizqvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:15 INFO - Running setup.py (path:/tmp/pip-PJrEZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:15 INFO - Running setup.py (path:/tmp/pip-p17C1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:15 INFO - Running setup.py (path:/tmp/pip-zVFp02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:15 INFO - Running setup.py (path:/tmp/pip-42hBva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:15 INFO - Running setup.py (path:/tmp/pip-7pi8Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:15 INFO - Running setup.py (path:/tmp/pip-S11enE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:15 INFO - Running setup.py (path:/tmp/pip-4G73Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:15 INFO - Running setup.py (path:/tmp/pip-E2WobJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:15 INFO - Running setup.py (path:/tmp/pip-pvdMbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:15 INFO - Running setup.py install for manifestparser 04:13:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:15 INFO - Running setup.py install for mozcrash 04:13:16 INFO - Running setup.py install for mozdebug 04:13:16 INFO - Running setup.py install for mozdevice 04:13:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Running setup.py install for mozfile 04:13:16 INFO - Running setup.py install for mozhttpd 04:13:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Running setup.py install for mozinfo 04:13:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Running setup.py install for mozInstall 04:13:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Running setup.py install for mozleak 04:13:16 INFO - Running setup.py install for mozlog 04:13:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Running setup.py install for moznetwork 04:13:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:16 INFO - Running setup.py install for mozprocess 04:13:17 INFO - Running setup.py install for mozprofile 04:13:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:17 INFO - Running setup.py install for mozrunner 04:13:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:17 INFO - Running setup.py install for mozscreenshot 04:13:17 INFO - Running setup.py install for moztest 04:13:17 INFO - Running setup.py install for mozversion 04:13:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:17 INFO - Cleaning up... 04:13:17 INFO - Return code: 0 04:13:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:17 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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:17 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 04:13:17 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 04:13:17 INFO - Using env: {'DISPLAY': ':0', 04:13:17 INFO - 'HOME': '/home/cltbld', 04:13:17 INFO - 'LANG': 'en_US.UTF-8', 04:13:17 INFO - 'LANGUAGE': 'en_US:en', 04:13:17 INFO - 'LOGNAME': 'cltbld', 04:13:17 INFO - 'MAIL': '/var/mail/cltbld', 04:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:17 INFO - 'MOZ_NO_REMOTE': '1', 04:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:17 INFO - 'NO_EM_RESTART': '1', 04:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:17 INFO - 'PWD': '/builds/slave/test', 04:13:17 INFO - 'SHELL': '/bin/bash', 04:13:17 INFO - 'SHLVL': '1', 04:13:17 INFO - 'TERM': 'linux', 04:13:17 INFO - 'TMOUT': '86400', 04:13:17 INFO - 'USER': 'cltbld', 04:13:17 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:17 INFO - '_': '/tools/buildbot/bin/python'} 04:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:17 INFO - Running setup.py (path:/tmp/pip-5Gctfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:17 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)) 04:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:17 INFO - Running setup.py (path:/tmp/pip-jFZ6g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:17 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)) 04:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:17 INFO - Running setup.py (path:/tmp/pip-25kiVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:18 INFO - Running setup.py (path:/tmp/pip-eb0F4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:18 INFO - Running setup.py (path:/tmp/pip-dkvLCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:18 INFO - Running setup.py (path:/tmp/pip-EFoRG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:18 INFO - Running setup.py (path:/tmp/pip-GKEscL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:18 INFO - Running setup.py (path:/tmp/pip-gigXgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:18 INFO - Running setup.py (path:/tmp/pip-bk4wqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:18 INFO - Running setup.py (path:/tmp/pip-tdSui1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:18 INFO - Running setup.py (path:/tmp/pip-053Ewr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:18 INFO - Running setup.py (path:/tmp/pip-MTWFTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:18 INFO - Running setup.py (path:/tmp/pip-3rqFKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:18 INFO - Running setup.py (path:/tmp/pip-0xqNcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:18 INFO - Running setup.py (path:/tmp/pip-YzDmaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:18 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)) 04:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:18 INFO - Running setup.py (path:/tmp/pip-HRRMbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:19 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)) 04:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:19 INFO - Running setup.py (path:/tmp/pip-nhl4OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:19 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)) 04:13:19 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)) 04:13:19 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)) 04:13:19 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)) 04:13:19 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)) 04:13:19 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)) 04:13:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:21 INFO - Downloading blessings-1.6.tar.gz 04:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:21 INFO - Installing collected packages: blessings 04:13:21 INFO - Running setup.py install for blessings 04:13:21 INFO - Successfully installed blessings 04:13:21 INFO - Cleaning up... 04:13:21 INFO - Return code: 0 04:13:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:21 INFO - Reading from file tmpfile_stdout 04:13:21 INFO - Current package versions: 04:13:21 INFO - argparse == 1.2.1 04:13:21 INFO - blessings == 1.6 04:13:21 INFO - blobuploader == 1.2.4 04:13:21 INFO - docopt == 0.6.1 04:13:21 INFO - functools32 == 3.2.3-2 04:13:21 INFO - jsonschema == 2.5.1 04:13:21 INFO - manifestparser == 1.1 04:13:21 INFO - mozInstall == 1.12 04:13:21 INFO - mozcrash == 0.17 04:13:21 INFO - mozdebug == 0.1 04:13:21 INFO - mozdevice == 0.48 04:13:21 INFO - mozfile == 1.2 04:13:21 INFO - mozhttpd == 0.7 04:13:21 INFO - mozinfo == 0.9 04:13:21 INFO - mozleak == 0.1 04:13:21 INFO - mozlog == 3.2 04:13:21 INFO - moznetwork == 0.27 04:13:21 INFO - mozprocess == 0.23 04:13:21 INFO - mozprofile == 0.28 04:13:21 INFO - mozrunner == 6.12 04:13:21 INFO - mozscreenshot == 0.1 04:13:21 INFO - mozsystemmonitor == 0.3 04:13:21 INFO - moztest == 0.7 04:13:21 INFO - mozversion == 1.4 04:13:21 INFO - psutil == 3.1.1 04:13:21 INFO - requests == 1.2.3 04:13:21 INFO - wsgiref == 0.1.2 04:13:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 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 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:21 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 04:13:21 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 04:13:21 INFO - Using env: {'DISPLAY': ':0', 04:13:21 INFO - 'HOME': '/home/cltbld', 04:13:21 INFO - 'LANG': 'en_US.UTF-8', 04:13:21 INFO - 'LANGUAGE': 'en_US:en', 04:13:21 INFO - 'LOGNAME': 'cltbld', 04:13:21 INFO - 'MAIL': '/var/mail/cltbld', 04:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:21 INFO - 'MOZ_NO_REMOTE': '1', 04:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:21 INFO - 'NO_EM_RESTART': '1', 04:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:21 INFO - 'PWD': '/builds/slave/test', 04:13:21 INFO - 'SHELL': '/bin/bash', 04:13:21 INFO - 'SHLVL': '1', 04:13:21 INFO - 'TERM': 'linux', 04:13:21 INFO - 'TMOUT': '86400', 04:13:21 INFO - 'USER': 'cltbld', 04:13:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:21 INFO - '_': '/tools/buildbot/bin/python'} 04:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 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)) 04:13:21 INFO - Cleaning up... 04:13:21 INFO - Return code: 0 04:13:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1952030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', '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 04:13:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:13:21 INFO - Using env: {'DISPLAY': ':0', 04:13:21 INFO - 'HOME': '/home/cltbld', 04:13:21 INFO - 'LANG': 'en_US.UTF-8', 04:13:21 INFO - 'LANGUAGE': 'en_US:en', 04:13:21 INFO - 'LOGNAME': 'cltbld', 04:13:21 INFO - 'MAIL': '/var/mail/cltbld', 04:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:21 INFO - 'MOZ_NO_REMOTE': '1', 04:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:21 INFO - 'NO_EM_RESTART': '1', 04:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:21 INFO - 'PWD': '/builds/slave/test', 04:13:21 INFO - 'SHELL': '/bin/bash', 04:13:21 INFO - 'SHLVL': '1', 04:13:21 INFO - 'TERM': 'linux', 04:13:21 INFO - 'TMOUT': '86400', 04:13:21 INFO - 'USER': 'cltbld', 04:13:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:21 INFO - '_': '/tools/buildbot/bin/python'} 04:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:21 INFO - Cleaning up... 04:13:21 INFO - Return code: 0 04:13:21 INFO - Running post-action listener: _resource_record_post_action 04:13:21 INFO - Running post-action listener: _start_resource_monitoring 04:13:21 INFO - Starting resource monitoring. 04:13:21 INFO - ##### 04:13:21 INFO - ##### Finished create-virtualenv step (success) 04:13:21 INFO - ##### 04:13:21 INFO - ##### 04:13:21 INFO - ##### Running install step. 04:13:21 INFO - ##### 04:13:21 INFO - Running pre-action listener: _resource_record_pre_action 04:13:21 INFO - Running main action method: install 04:13:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:22 INFO - Reading from file tmpfile_stdout 04:13:22 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:22 INFO - Reading from file tmpfile_stdout 04:13:22 INFO - Output received: 04:13:22 INFO - Usage: mozinstall [options] installer 04:13:22 INFO - Options: 04:13:22 INFO - -h, --help show this help message and exit 04:13:22 INFO - -d DEST, --destination=DEST 04:13:22 INFO - Directory to install application into. [default: 04:13:22 INFO - "/builds/slave/test"] 04:13:22 INFO - --app=APP Application being installed. [default: firefox] 04:13:22 INFO - mkdir: /builds/slave/test/build/application 04:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:13:38 INFO - Reading from file tmpfile_stdout 04:13:38 INFO - Output received: 04:13:38 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:38 INFO - Running post-action listener: _resource_record_post_action 04:13:38 INFO - ##### 04:13:38 INFO - ##### Finished install step (success) 04:13:38 INFO - ##### 04:13:38 INFO - ##### 04:13:38 INFO - ##### Running run-tests step. 04:13:38 INFO - ##### 04:13:38 INFO - Running pre-action listener: _resource_record_pre_action 04:13:38 INFO - Running main action method: run_tests 04:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:38 INFO - Python 2.7.3 04:13:38 INFO - Return code: 0 04:13:38 INFO - grabbing minidump binary from tooltool 04:13:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:38 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 0x1c113b0>, '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 0x1c122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c12740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:38 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 04:13:38 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 04:13:38 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 04:13:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:38 INFO - Return code: 0 04:13:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13:38 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/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 04:13:38 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/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 04:13:38 INFO - Using env: {'DISPLAY': ':0', 04:13:38 INFO - 'HOME': '/home/cltbld', 04:13:38 INFO - 'LANG': 'en_US.UTF-8', 04:13:38 INFO - 'LANGUAGE': 'en_US:en', 04:13:38 INFO - 'LOGNAME': 'cltbld', 04:13:38 INFO - 'MAIL': '/var/mail/cltbld', 04:13:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:38 INFO - 'MOZ_NO_REMOTE': '1', 04:13:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:38 INFO - 'NO_EM_RESTART': '1', 04:13:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:38 INFO - 'PWD': '/builds/slave/test', 04:13:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:38 INFO - 'SHELL': '/bin/bash', 04:13:38 INFO - 'SHLVL': '1', 04:13:38 INFO - 'TERM': 'linux', 04:13:38 INFO - 'TMOUT': '86400', 04:13:38 INFO - 'USER': 'cltbld', 04:13:38 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361', 04:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:38 INFO - '_': '/tools/buildbot/bin/python'} 04:13:38 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/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 04:13:39 INFO - mozversion application_buildid: 20160714030744 04:13:39 INFO - mozversion application_changeset: 49ef0b8c3cfa522e35ef2fb937da43cecba6b99c 04:13:39 INFO - mozversion application_display_name: Nightly 04:13:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:39 INFO - mozversion application_name: Firefox 04:13:39 INFO - mozversion application_remotingname: firefox 04:13:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:13:39 INFO - mozversion application_vendor: Mozilla 04:13:39 INFO - mozversion application_version: 50.0a1 04:13:39 INFO - mozversion platform_buildid: 20160714030744 04:13:39 INFO - mozversion platform_changeset: 49ef0b8c3cfa522e35ef2fb937da43cecba6b99c 04:13:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:13:39 INFO - mozversion platform_version: 50.0a1 04:13:39 INFO - using testdate: 1468494819 04:13:39 INFO - actual date: 1468494819 04:13:39 INFO - starting webserver on 'localhost:42444' 04:13:39 INFO - SUITE-START | Running 2 tests 04:13:39 INFO - TEST-START | dromaeo_css 04:13:39 INFO - operating with platform_type : linux_ 04:13:39 INFO - Initialising browser for dromaeo_css test... 04:13:39 INFO - TEST-INFO | started process 3312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHpRClZ/profile http://localhost:42444/getInfo.html) 04:13:46 INFO - PROCESS | 3312 | __metrics Screen width/height:1600/1200 04:13:46 INFO - PROCESS | 3312 | colorDepth:24 04:13:46 INFO - PROCESS | 3312 | Browser inner width/height: 1024/697 04:13:46 INFO - PROCESS | 3312 | __metrics 04:13:47 INFO - PROCESS | 3312 | 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" 04:13:47 INFO - PROCESS | 3312 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:13:47 INFO - PROCESS | 3312 | console.error: PushService: 04:13:47 INFO - PROCESS | 3312 | stateChangeProcessEnqueue: Error transitioning state 04:13:47 INFO - PROCESS | 3312 | UnknownError 04:13:48 INFO - TEST-INFO | 3312: exit 0 04:13:48 INFO - Browser initialized. 04:13:48 INFO - Running cycle 1/1 for dromaeo_css test... 04:13:48 INFO - TEST-INFO | started process 3396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHpRClZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:00 INFO - PROCESS | 3396 | RSS: Main: 159076352 04:14:00 INFO - PROCESS | 3396 | 04:14:52 INFO - PROCESS | 3396 | 1468494892366 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] 04:17:04 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:42444/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42444/tests/dromaeo/cssquery-ext.html) 04:17:05 INFO - PROCESS | 3396 | RSS: Main: 270557184 04:17:05 INFO - PROCESS | 3396 | 04:20:08 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:42444/tests/dromaeo/cssquery-ext.html (next: http://localhost:42444/tests/dromaeo/cssquery-jquery.html) 04:20:08 INFO - PROCESS | 3396 | RSS: Main: 242855936 04:20:08 INFO - PROCESS | 3396 | 04:23:10 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:42444/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42444/tests/dromaeo/cssquery-mootools.html) 04:23:11 INFO - PROCESS | 3396 | RSS: Main: 235040768 04:23:11 INFO - PROCESS | 3396 | 04:26:13 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:42444/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42444/tests/dromaeo/cssquery-prototype.html) 04:26:14 INFO - PROCESS | 3396 | RSS: Main: 234115072 04:26:14 INFO - PROCESS | 3396 | 04:29:17 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:42444/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42444/tests/dromaeo/cssquery-yui.html) 04:29:18 INFO - PROCESS | 3396 | RSS: Main: 211300352 04:29:18 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:42444/tests/dromaeo/cssquery-yui.html (next: http://localhost:42444/tests/dromaeo/cssquery-dojo.html) 04:32:21 INFO - PROCESS | 3396 | RSS: Main: 275546112 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | __start_tp_report 04:32:21 INFO - PROCESS | 3396 | _x_x_mozilla_page_load 04:32:21 INFO - PROCESS | 3396 | _x_x_mozilla_page_load_details 04:32:21 INFO - PROCESS | 3396 | |i|pagename|runs| 04:32:21 INFO - PROCESS | 3396 | |0;dojo.html;2630.2635262126605;2642.2865826226907;2673.024346113668;2675.8126931114816;2682.369643133863;4569.702969306993;4668.6731928765;4683.859444303084;4748.074125545516;4796.184648609736;4626.606738427237;4631.837885673994;4633.467151277604;4670.136024835408;4689.132510485562;4062.573429789874;4234.428352172464;4302.247106756321;4331.870043898688;4399.340098985146;4326.30779075348;4354.7604881731495;4369.606735393814;4377.168338015783;4402.1195760847795;4194;4232.217039847638;4237.385579091015;4263.104748002923;4319.589638984291;4255.255330317213;4265.466816647919;4279.229738647043;4294.742315461082;4327.285997810358;4242.130363275521;4288.69979101463;4293.892652683677;4298.26929421999;4336.522982471925;4493.348464472666;4661.394018777572;4688.906221875543;4706.811727530895;4720.126776546315;4984.526469985346;5059.367579052619;5099.87250318745;5120.564751996085;5181.844544663666;5132.692038477666;5151.04705629456;5189;5206.973965130188;5212.582993360522;4573.565511276423;4651.720896746138;4810.302506136661;4855.393075865517;4868.440129385095;5120.308758317579;5125.436202017775;5126.794928202905;5162.406323272797;5170.870728231749;4941.308216849644;4985.351904252424;4990.90018199634;4994.625403094782;4995.100881841231;4849.975750121297;4879.1461494238365;4895.534924182268;5015.3730783652045;5025.422076461181;4891.657583969088;4907.926500807981;4909;4911.042346742351;4950.059488697136;4935;4935.062338155739;4935.111679897581;4938.975305123523;4974.0797952378925;4076.7553946763287;4119.8970025748995;4126.174765047002;4153.335466325434;4244.193603215379;3672.2839046385698;3681.4293784463453;3696.685781708531;3726.552813662324;3747.138158223624;4285.61429471349;4285.8285668573535;4289.892752681146;4323.48118225815;4331.978340108342;4810.759462026955;4904.386951631046;4973.055119527278;4973.254011898244;5044.2937988681615;4265.7014009019695;4288.24955632763;4292.527821939584;4295.742255464682;4337.457817772778;4403.207422663952;4442.222611043055;4446.866594002185;4486.371907932892;4486.7083639563325;4511;4623.86128416148;4739.668223224479;4802.8799280018275;4812.302216178565;5041.470645582308;5046.940142570102;5055.317532133115;5069.670471419346;5115.769790359517;4593.908121837611;4790.329353890388;4804.039192161512;4805.918668299604;4852.223644216909;4938.160512712776;4975;5032.899342013213;5051.797928082836;5054.2671312660195;5075.264086707333;5081.110805609078;5104.132297509358;5114.974425127851;5164.04465173944;4658.697184682985;4816.8554943351755;4951.77717002743;4959.305697202467;4961.404631444249;4820;4870.756462176948;4884.194107972145;4885.389326334208;4931.6301277405055;4805.063012712488;4805.087033463631;4832.63755218353;4841.903161936812;4846.17615005457;4746.715197088186;4788.7366194859615;4896.681715688469;4902.044101400193;4917.557419832232;4834.057358814997;4843;4862.7325497098;4866;4937.506249375034 04:32:21 INFO - PROCESS | 3396 | |1;ext.html;544.273670527394;566.3826429192179;584.9678267695275;585.6808039618408;600.2137200267648;37979.24041519168;39000.609993900085;39065.41401878971;39353.40969885454;43428;40880.38679419811;41422.792886035524;41427;41427.79286103573;41482.58517414825;7679.500832445901;7898.723544675938;8034.959825200865;10121.08910198082;10382.221333399988;10144.644937427192;10238.590456381737;10241.436720980342;10243.129334006619;10244.436555989418;10313.535890884928;10342.172626189924;10363.222758293157;10367.688969330931;10369.948150259275;7736.497127686683;10168.898311016907;10390.116840068604;10399.948000260027;10747.83878241827;10160.847587286196;10257.384556926609;10263.486825658687;10265.743356416075;10268.357062869905;29745.25636859096;38968.220635587415;39369;39377.40933885994;39449.21101577981;2869.383676175195;2964.7035296470394;3043.5891154694054;3101.503759398508;3176.857041433141;27020.729792702215;27489.587656185173;30423.23941901448;30482.08553743391;31044.689553104407;2991.641003079644;2992.8054676445963;3019.064090132066;3050.5424186372;3084;264527;265989;269523;270534;270814;48304.27543586849;56819;57469.42530574641;57502.7124864373;57573.13640295399;56235;57257.71371143118;57277;64803;65381.67309163519;12933.482660693655;14348.641283967985;14624.780628290584;14664.926675366554;14673.633159170893;9835.262355323379;10745.785084298426;11043.889561104446;11046.33721976684;11374.260673056226;19048.90475547641;19058.14238359277;19115.36808006502;19145.13846876894;19147.13837877299;7218.314260145278;7288.234735352818;7320.853582928418;7379.631018449056;7440.888386674204;810.7648781853196;885.8848349714495;897.6858099665195;929.1173385284008;952.6951375559893;26973.86513067422;27368.45263094727;28029.859850701025;28326.575101373495;28456;952.2810278240023;980.9264305176997;993.438707130469;1064.3135177810268;1081.4106312059946;14571;14940.626484337978;14980.475683351031;15022.624434389229;15237.238138093051;396651;427664;435024;436359;660319;50539.74730126326;51031.744841275555;51236.74381628143;51256.74371628118;51335;1088.7931293054294;1179.4633441783797;1182.438341787661;1190.190670344157;1193.2959553863263;27385;29146.854265728536;29150;29844.253893652833;29943.70056299409;42257.366139508544;42939.141217176104;42990.78504607582;42996.14007719891;43014;306619;313182;313886;314616;315722;22304.21935232259;24414.63378049295;24788.628170577816;25467;25827.483450330514;41430.585694142675;41714.58285417107;41925.79037104917;42164.156716864876;42198.57801422069;36239;36586;36595.63404365922;36722.63277367299;36923.630763693094;35707;36686.082847929014;36751.632483674824;36815.63184368122;37232.81383593173;35485.290294194485;35516.467252991744;35822.8208858954;36201.45697814482;36616;31559.144077607227;31960.520592191602;32074.358512829145;32700.83649581737;32724.50913236351;25143.245702628952;25427;25552;25622.743772562037;25976.090836820622 04:32:21 INFO - PROCESS | 3396 | |2;jquery.html;345.4955764583711;382.7187017542106;384.1491097219659;385.2449199568845;399.63034193371124;12412.131150819447;16648.833511664896;17119.40082097127;17274;17415.21631526581;16416.09711465869;16952;17332.913335433335;17554.473365799015;17705.645887082283;1403.6982048859493;1410.9576712698636;1413.1238632048094;1639.0821140161547;5299.947000529984;5550.306211723535;5571.888562228753;5656.038473459501;5676.886462270752;5678.517326027272;5599.888002239953;5600.18797274395;5602.915956260659;5604.439556044404;6185.907211391833;6186.659733714644;6211;6243.375662433766;6273.874522509547;6276.372362763733;5308.6549374290935;5521.917171242435;5531.7234138293325;5535.916961245584;5540.836424350891;13074.477020919152;13162.802557961639;13163.605091847165;15940.840591594051;22024;1688.7129188038064;1766.620176662011;1786.0712429536568;1788.1416919878402;1832.5968286976977;9497.762555936088;9847.261455390802;13317.735984882593;13458.798118028237;14129.576112716635;1739.2086600596824;1764.1443899708631;1787.865910056738;1812.4562631210715;1837.4946889605333;67147.66426167887;68366.31633683649;71495.28504714939;71974.28025719781;72918;8952.68665596701;9609.423434593948;10377.273590848568;10383.792324153475;10491.475426228659;12371.75256494865;13371.33143342829;13391.933040334736;13669.248191349372;13760.105593136414;6850.725970961205;7126.501144919909;7203.387712044535;7216.494845360774;7252.5648461092505;6292.433680968735;6300.495960323163;6377.4579160771855;6774.085498457645;6934.687939042654;3904.750479846479;3990.660793832557;3997.6601988831276;4012.879613611596;4158.251514727378;1848.879822811513;1863.18951256204;1863.413024897153;1883.8587105967108;1910.5987742574216;736.6795443981778;847.2882778466118;858.1547176031604;884.8053428245776;894.49461054504;17359.045252511234;17410.564735881704;17764.46706598804;18270.817291827178;18516.907415462836;810.278851821879;812.3907069697726;815.8286759780394;844.5861527851431;871.2550769092461;9708.564009968288;10036.397816131055;10060.899391006142;10061.849072263776;10161.695149145537;345346;350874;351182;358424;362645;77287;77709.61145194237;79698.6015069921;79847.60076199583;81376.59311703523;807.5518087461516;816.31429599137;830.2030051150842;836.122071824594;841.1420351241821;15423.61440963985;15817.130057846929;15827.129507877178;15864.603384915008;16465.25906334194;26133.73866261313;26827.999999999218;37586.436203456426;38914;39014.804925975186;48550.27174592455;48687.26969095537;49210.75394623004;49476;49677.503224968736;7279.162896266997;7571.242875712384;7588.468807183444;7665.6550455228535;7704.768856934077;10605.41670208146;11279.913216055056;11405.144614154138;11474.139439542234;11544.19190656646;9743.9025609743;9754.219662427131;9757.658481953098;10014.899851001397;10126.493675316353;9873.950630246803;9900.45547494866;10046.196304295785;10142.898571014195;10169.644062458074;9806.166475849348;10090.747731306776;10226.437545935045;10304.433256170683;10742;7340.669669864762;7564.092308922965;7669.9616501917135;7713.151553329241;7744.5740484271655;9175.174234318943;9728.17310528613;9948.651797187063;9994;10183 04:32:21 INFO - PROCESS | 3396 | |3;mootools.html;1363.870432308931;1409.0488919979016;1474.5723740115368;1704.4716137997223;1725.8619310455165;7784.883226751596;8220.506769593829;8282.958585207072;8287.83424331513;8288.751337459871;6692.130023097003;7674;7997.080335761375;8009.319207867338;8159.592020398986;1902;1925.210663627906;1926.5472613935715;1928.980710192894;1932.352661858272;1883.9717404238947;2230.7323121225395;2234.597772400967;2253.876036817974;2267.1724820440586;2113.123053932616;2190.386691726319;2201.207565276507;2212.9114835406563;2217.5010622609943;2185.3225500094863;2198.373463562885;2207.724034495688;2216.656418255173;2218.9334319970426;2119.3747844385944;2127.425595089317;2127.914883404662;2128.957420851574;2139.0695047654162;8058.1538938411795;8142.592870356459;8157.388195885273;8163.836723265561;8170.1829817018415;4551.294549344857;4678.274867395559;4679.438467383901;4685.226937555333;4686.507916668735;2665.8800353984116;2718.5319927239266;2751.0233866977137;2773.486904922596;2773.861306934665;830.7009476588423;833.6623667414707;834.1866679987024;843.9535825529593;844.4426678392293;11194.776104477945;11208.103351731841;11229.887701122965;11249.493772780244;11259.887401125965;4000.3799411091327;4112.074783173822;4237.237297286456;4240.597143271384;4267.445232119867;9382.48396338208;9435.858462123073;9458.95270523643;9459.574319155654;9466.526673666427;4585.724856508554;4597.195490789099;4597.241455159861;4599.4710608280475;4604.953950460453;4059.7970101494807;4123.4021066945725;4133.04939863827;4138.048248902769;4141.316682747373;3766.6798322142927;3803.6576708096404;3814.7901865397116;3851.8844434667003;3883.572806991229;1067.9466026698633;1151.976960460803;1153.4636394076795;1189.1497579230718;1190.880911908802;817.3502065857539;821.5112008355019;829.4732844643695;840.495702578448;851.3699862102;5109.733372633892;5212.40057393397;5238.92141617876;5262.92105618416;5308.362996440452;858.0861382627481;858.3433673239977;888.200619442494;904.9638014479347;914.4833169259347;3675.632436756303;3753.1930634913624;3814.2180852925085;3884.9223015539533;3951.9012024699614;9759.560819763128;10126.594936202617;10225.642102526375;10229.897701022894;10231.800162964999;9405.247580193569;10124.59501619927;10129.442880641636;10138.797224055477;10142;7418.777436676908;7765.961170194226;7788.065432148179;7789.766307010798;7793.649285782153;5273.499017593323;5374.811881584125;5444.564434845123;5510.201020852034;5553.694546799965;8838.292936565187;9268.675596354095;9269.397489163182;9323.580438880132;9369.344145909721;9225.169734723908;9327.486988215713;9400.95299523498;9649.710508684751;9771.364861283993;6824.965875170592;6912.308769123048;6927.3419025192525;6970.23327433992;6979.267176946348;7688.308052275322;7857.764267071997;7876.8818467721885;7880.05439347282;7880.56656883854;5285.550728187994;6909.619970901648;7021.192562855337;7137.928620713727;7206.855862882818;6818.215905170969;6846.897296540656;6860.794176174722;7145.642717863982;7231.783046508614;6693.799186024427;6712.362325579063;6777.593344399352;6782.152230971129;6782.1861376635115;4429.059557391989;4558.6581006423985;4589.977050114728;4678.415198100238;4707.741074240949;4690.882727931829;4694.835680751157;4742.928856067093;4903.754812259444;4918.508149185053 04:32:21 INFO - PROCESS | 3396 | |4;prototype.html;304.30010974758056;307.86761692472226;310.1950438611803;312.89518011466157;314.48581569134456;4858.34412354332;4900.191468407717;4910.828121015765;4919.212925931852;4930.482299358569;4770.928436073453;4787;4816.807327706896;4841.709497430156;4857.174280372336;3629.364861149308;3719.293334266494;3719.758215715983;3811.1234416084317;3818.522684664417;3756.3989761638145;3801.8289176986964;3818.713596480261;3825.6036546660407;3839.1361943562756;3785.5268091488565;3785.886423407302;3794.563625183098;3803.8098095095274;3807.6863482098634;3558.5195998540125;3652.6164752700847;3799.3920972644246;3806.54321481424;3811.3711237645757;3364.52896594477;3366.545516355285;3373.9156521086925;3376.9831150844334;3377.5946886373545;4737.289406589005;4738.715677059353;4738.914877453909;4739.715617062952;4747.7388743619085;4167.5832416758385;4291.163223171483;4333.371661109153;4340.565943405666;4347.5652434756585;4971.577415919651;4990.101781679296;5003.324551185579;5005.599552035865;5021.748912554358;3680.300742858875;3691.0956815580084;3711.3876210425246;3718.7768733875782;3718.9628103718887;5045.142325804622;5243.475652434802;5289.656172348824;5308.6549374290935;5317.388500322474;3719.628037196313;3728.422094575345;3729.366007778684;3747.362948298796;3760.7555508891833;3792.1657235408165;3793.5827059023486;3799.3920972644796;3800.809959502014;3804.6195380462286;4311.0300182458695;4316.330968699856;4324.2648749712625;4326.870193894189;4332.285172946492;4877.146499362598;5014.423341315723;5023.723695196799;5026.572741316956;5089.949100509021;1295.6825577733516;1306.7190554030929;1313.2973858985397;1319.3667039820748;1320.221069568959;852.9147085291421;853.9018012928468;869.5261082709939;876.7106854737921;880.9647614095493;4338.982373196479;4375.693701440932;4390.297552391602;4467.642588592905;4491.708038977456;5152.6650767699975;5171.948280517222;5188.481151884856;5211.374635043819;5232.39827419844;4298.183345164472;4416.889577760582;4440.067585806952;4452.666050046259;4463.843765468193;2736.86315684215;2748.9037883674378;2750.8624568771484;2752.1330780803983;2758.4207316463367;4899.412070551557;4905.877353066202;4911.7298548580175;4919.7540122993705;4928.334674818853;5002.424721157041;5259.896041583321;5264.578833693296;5273.156294992783;5286.471352864683;4865.783554111472;4919.90160196794;4922.75386230687;4930.309756634146;4963;5101.79592816298;5134.9486505135965;5137.383513978347;5151.613628977917;5199.154300223367;4856.927146092741;5044.47033061538;5068.2904393384215;5096.0827051131155;5168.3797944245425;4955.2567114933045;4964.67729597575;4975.875603109951;4981.452040275494;4984.22744474607;3690.3357395667963;3710.4990826238113;3717.4795528625473;3743.5694895086326;3745.8314375853697;5082.136036873814;5204.401493828258;5296.973515132554;5307.283516725192;5307.6284660073425;5053.924191137063;5065.974670126626;5075.492450754895;5079.16193828029;5089.618278629192;5076.670016448919;5080.323493247146;5083.72039537829;5084.728817795552;5091.516305950929;4786.856394308176;5003.34956455673;5007.3240112584335;5008.323876276656;5046.34397528319;4869.099216644932;4941.357623508921;5108.974455127701;5114.95143495575;5129.615278854027;4981.334985782589;4993.3259010034635;4999.650024498395;5001.174806157089;5013.423456302572 04:32:21 INFO - PROCESS | 3396 | |5;yui.html;616.9290531588867;699.506847672391;699.8950157476374;730.9328380564376;772.8338407242444;12779.29713865737;13863.722725545484;14737.778933316009;15394.61513462164;15435.536933891974;15318.46385376512;15329.693406131899;15404;15463.30415131319;15497.225138743073;625.4308579192935;748.3377211168101;785.1598789295456;792.13657113746;797.0554892452462;795.7652492514693;800.5396896784343;801.2388231180373;801.3749275565068;801.4670244287541;837.3092198935872;839.1901814748766;839.2488722593275;839.6347588798892;839.8278352937664;649.5680372552247;795.5942469340649;818.3371469109981;822.0874828939872;824.4435006370736;603.3393434189555;603.5654328883197;604.114971566657;604.1210039392673;605.1799811255729;13506.729865402735;13525.39135738894;13526.391312390966;13559.59321220365;13562.389692463863;11900.166988310822;12214.450349734194;12278.263304201688;12829.67925801853;12903.35483225835;10441.582336706522;10446.529906154241;10504.632337868214;10810.72973175669;11269.549218031269;3366.882159124443;3383.813890236036;3398.762086653935;3492.755507114503;3517.4723791431234;62736;62994.37005629931;63013.68493157505;63446;64157;10303.227257955534;10591.576336946437;10649.787004259872;10695.83956240657;10726.141908647289;18726;19046.523836904187;19048.71426928597;19070.42788716313;19101.331453399063;6794.524383293221;6961.721531138698;7007.299270072952;7063.1524217094275;7199.1361036675935;3678.2275722098657;3690.8892733218045;3706.258748250307;3713.814309284579;3774.867879624255;2904.3320036391747;2998.9850050749606;3001.4147241287737;3002.579638850563;3008.368242669064;475.46272711835405;494.37708487306253;494.675987228366;503.17228159677126;505.0505050505027;404.0706375336714;407.5089517131908;413.4769516561552;499.8475464983215;531.4472948133974;7232.566046037255;7413.925860741431;7474.439417043744;7542.208068152765;7622.885656715153;466.44493053266507;487.2520680755095;495.4327295246922;501.29567957020447;516.880952737327;3391.6099648540735;3512.7892326460496;3543.8050907199836;3599.298136863339;3735.831887565066;98781;103964.48017760014;105614;105871;108158.45920770503;88391;88684;88911.55544222367;89723.10276897147;89813;80925.59537202277;82384;82415.58792206;83008;83190;6979.651017449006;7149.571025738473;7186.173590037212;7188.820279493055;7330;48039.51960480351;48657.5134248653;49120;50393.74803126108;51469;61448.69275653772;69218.30781692118;69949;70048;71282.64358678173;8449.915500845158;8677.739667809976;8691.217790398894;8711.172438618321;8866.290696744374;10683.412412317397;10858.837117443087;10954.068904143238;11131.944340278247;11255.662330129782;9139.086091390807;9252.259819214312;9305.767355816159;9387.295952803704;9446.24430045581;8897.911020889967;8973.730788076107;9119.594992891392;9136.177744003071;9154.450732956044;8792.296616270554;8981.775455613662;9275.211607013209;9383.530823458936;9399.201067909304;8486.278666313432;8536.658533658585;8975.640974361213;9498.071419683962;9811.803763924825;9240.95379523098;9334.906650933404;9654.27592930519;9664.275179361717;9681.70954871355 04:32:21 INFO - PROCESS | 3396 | __end_tp_report 04:32:21 INFO - PROCESS | 3396 | __start_cc_report 04:32:21 INFO - PROCESS | 3396 | _x_x_mozilla_cycle_collect,1067 04:32:21 INFO - PROCESS | 3396 | __end_cc_report 04:32:21 INFO - PROCESS | 3396 | __startTimestamp1468495941381__endTimestamp 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | ------- Summary: start ------- 04:32:21 INFO - PROCESS | 3396 | Number of tests: 6 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | [#0] dojo.html Cycles:165 Average:4631.39 Median:4805.06 stddev:495.42 (10.3%) stddev-sans-first:471.41 04:32:21 INFO - PROCESS | 3396 | Values: 2630.3 2642.3 2673.0 2675.8 2682.4 4569.7 4668.7 4683.9 4748.1 4796.2 4626.6 4631.8 4633.5 4670.1 4689.1 4062.6 4234.4 4302.2 4331.9 4399.3 4326.3 4354.8 4369.6 4377.2 4402.1 4194.0 4232.2 4237.4 4263.1 4319.6 4255.3 4265.5 4279.2 4294.7 4327.3 4242.1 4288.7 4293.9 4298.3 4336.5 4493.3 4661.4 4688.9 4706.8 4720.1 4984.5 5059.4 5099.9 5120.6 5181.8 5132.7 5151.0 5189.0 5207.0 5212.6 4573.6 4651.7 4810.3 4855.4 4868.4 5120.3 5125.4 5126.8 5162.4 5170.9 4941.3 4985.4 4990.9 4994.6 4995.1 4850.0 4879.1 4895.5 5015.4 5025.4 4891.7 4907.9 4909.0 4911.0 4950.1 4935.0 4935.1 4935.1 4939.0 4974.1 4076.8 4119.9 4126.2 4153.3 4244.2 3672.3 3681.4 3696.7 3726.6 3747.1 4285.6 4285.8 4289.9 4323.5 4332.0 4810.8 4904.4 4973.1 4973.3 5044.3 4265.7 4288.2 4292.5 4295.7 4337.5 4403.2 4442.2 4446.9 4486.4 4486.7 4511.0 4623.9 4739.7 4802.9 4812.3 5041.5 5046.9 5055.3 5069.7 5115.8 4593.9 4790.3 4804.0 4805.9 4852.2 4938.2 4975.0 5032.9 5051.8 5054.3 5075.3 5081.1 5104.1 5115.0 5164.0 4658.7 4816.9 4951.8 4959.3 4961.4 4820.0 4870.8 4884.2 4885.4 4931.6 4805.1 4805.1 4832.6 4841.9 4846.2 4746.7 4788.7 4896.7 4902.0 4917.6 4834.1 4843.0 4862.7 4866.0 4937.5 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | [#1] ext.html Cycles:180 Average:50713.32 Median:173087.57 stddev:96922.32 (56.0%) stddev-sans-first:97121.02 04:32:21 INFO - PROCESS | 3396 | Values: 544.3 566.4 585.0 585.7 600.2 37979.2 39000.6 39065.4 39353.4 43428.0 40880.4 41422.8 41427.0 41427.8 41482.6 7679.5 7898.7 8035.0 10121.1 10382.2 10144.6 10238.6 10241.4 10243.1 10244.4 10313.5 10342.2 10363.2 10367.7 10369.9 7736.5 10168.9 10390.1 10399.9 10747.8 10160.8 10257.4 10263.5 10265.7 10268.4 29745.3 38968.2 39369.0 39377.4 39449.2 2869.4 2964.7 3043.6 3101.5 3176.9 27020.7 27489.6 30423.2 30482.1 31044.7 2991.6 2992.8 3019.1 3050.5 3084.0 264527.0 265989.0 269523.0 270534.0 270814.0 48304.3 56819.0 57469.4 57502.7 57573.1 56235.0 57257.7 57277.0 64803.0 65381.7 12933.5 14348.6 14624.8 14664.9 14673.6 9835.3 10745.8 11043.9 11046.3 11374.3 19048.9 19058.1 19115.4 19145.1 19147.1 7218.3 7288.2 7320.9 7379.6 7440.9 810.8 885.9 897.7 929.1 952.7 26973.9 27368.5 28029.9 28326.6 28456.0 952.3 980.9 993.4 1064.3 1081.4 14571.0 14940.6 14980.5 15022.6 15237.2 396651.0 427664.0 435024.0 436359.0 660319.0 50539.7 51031.7 51236.7 51256.7 51335.0 1088.8 1179.5 1182.4 1190.2 1193.3 27385.0 29146.9 29150.0 29844.3 29943.7 42257.4 42939.1 42990.8 42996.1 43014.0 306619.0 313182.0 313886.0 314616.0 315722.0 22304.2 24414.6 24788.6 25467.0 25827.5 41430.6 41714.6 41925.8 42164.2 42198.6 36239.0 36586.0 36595.6 36722.6 36923.6 35707.0 36686.1 36751.6 36815.6 37232.8 35485.3 35516.5 35822.8 36201.5 36616.0 31559.1 31960.5 32074.4 32700.8 32724.5 25143.2 25427.0 25552.0 25622.7 25976.1 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | [#2] jquery.html Cycles:180 Average:23101.78 Median:3994.16 stddev:58746.74 (1470.8%) stddev-sans-first:58886.69 04:32:21 INFO - PROCESS | 3396 | Values: 345.5 382.7 384.1 385.2 399.6 12412.1 16648.8 17119.4 17274.0 17415.2 16416.1 16952.0 17332.9 17554.5 17705.6 1403.7 1411.0 1413.1 1639.1 5299.9 5550.3 5571.9 5656.0 5676.9 5678.5 5599.9 5600.2 5602.9 5604.4 6185.9 6186.7 6211.0 6243.4 6273.9 6276.4 5308.7 5521.9 5531.7 5535.9 5540.8 13074.5 13162.8 13163.6 15940.8 22024.0 1688.7 1766.6 1786.1 1788.1 1832.6 9497.8 9847.3 13317.7 13458.8 14129.6 1739.2 1764.1 1787.9 1812.5 1837.5 67147.7 68366.3 71495.3 71974.3 72918.0 8952.7 9609.4 10377.3 10383.8 10491.5 12371.8 13371.3 13391.9 13669.2 13760.1 6850.7 7126.5 7203.4 7216.5 7252.6 6292.4 6300.5 6377.5 6774.1 6934.7 3904.8 3990.7 3997.7 4012.9 4158.3 1848.9 1863.2 1863.4 1883.9 1910.6 736.7 847.3 858.2 884.8 894.5 17359.0 17410.6 17764.5 18270.8 18516.9 810.3 812.4 815.8 844.6 871.3 9708.6 10036.4 10060.9 10061.8 10161.7 345346.0 350874.0 351182.0 358424.0 362645.0 77287.0 77709.6 79698.6 79847.6 81376.6 807.6 816.3 830.2 836.1 841.1 15423.6 15817.1 15827.1 15864.6 16465.3 26133.7 26828.0 37586.4 38914.0 39014.8 48550.3 48687.3 49210.8 49476.0 49677.5 7279.2 7571.2 7588.5 7665.7 7704.8 10605.4 11279.9 11405.1 11474.1 11544.2 9743.9 9754.2 9757.7 10014.9 10126.5 9874.0 9900.5 10046.2 10142.9 10169.6 9806.2 10090.7 10226.4 10304.4 10742.0 7340.7 7564.1 7670.0 7713.2 7744.6 9175.2 9728.2 9948.7 9994.0 10183.0 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | [#3] mootools.html Cycles:180 Average:5283.65 Median:4823.34 stddev:3044.71 (63.1%) stddev-sans-first:3039.00 04:32:21 INFO - PROCESS | 3396 | Values: 1363.9 1409.0 1474.6 1704.5 1725.9 7784.9 8220.5 8283.0 8287.8 8288.8 6692.1 7674.0 7997.1 8009.3 8159.6 1902.0 1925.2 1926.5 1929.0 1932.4 1884.0 2230.7 2234.6 2253.9 2267.2 2113.1 2190.4 2201.2 2212.9 2217.5 2185.3 2198.4 2207.7 2216.7 2218.9 2119.4 2127.4 2127.9 2129.0 2139.1 8058.2 8142.6 8157.4 8163.8 8170.2 4551.3 4678.3 4679.4 4685.2 4686.5 2665.9 2718.5 2751.0 2773.5 2773.9 830.7 833.7 834.2 844.0 844.4 11194.8 11208.1 11229.9 11249.5 11259.9 4000.4 4112.1 4237.2 4240.6 4267.4 9382.5 9435.9 9459.0 9459.6 9466.5 4585.7 4597.2 4597.2 4599.5 4605.0 4059.8 4123.4 4133.0 4138.0 4141.3 3766.7 3803.7 3814.8 3851.9 3883.6 1067.9 1152.0 1153.5 1189.1 1190.9 817.4 821.5 829.5 840.5 851.4 5109.7 5212.4 5238.9 5262.9 5308.4 858.1 858.3 888.2 905.0 914.5 3675.6 3753.2 3814.2 3884.9 3951.9 9759.6 10126.6 10225.6 10229.9 10231.8 9405.2 10124.6 10129.4 10138.8 10142.0 7418.8 7766.0 7788.1 7789.8 7793.6 5273.5 5374.8 5444.6 5510.2 5553.7 8838.3 9268.7 9269.4 9323.6 9369.3 9225.2 9327.5 9401.0 9649.7 9771.4 6825.0 6912.3 6927.3 6970.2 6979.3 7688.3 7857.8 7876.9 7880.1 7880.6 5285.6 6909.6 7021.2 7137.9 7206.9 6818.2 6846.9 6860.8 7145.6 7231.8 6693.8 6712.4 6777.6 6782.2 6782.2 4429.1 4558.7 4590.0 4678.4 4707.7 4690.9 4694.8 4742.9 4903.8 4918.5 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | [#4] prototype.html Cycles:180 Average:4210.90 Median:4857.05 stddev:1211.31 (24.9%) stddev-sans-first:1178.69 04:32:21 INFO - PROCESS | 3396 | Values: 304.3 307.9 310.2 312.9 314.5 4858.3 4900.2 4910.8 4919.2 4930.5 4770.9 4787.0 4816.8 4841.7 4857.2 3629.4 3719.3 3719.8 3811.1 3818.5 3756.4 3801.8 3818.7 3825.6 3839.1 3785.5 3785.9 3794.6 3803.8 3807.7 3558.5 3652.6 3799.4 3806.5 3811.4 3364.5 3366.5 3373.9 3377.0 3377.6 4737.3 4738.7 4738.9 4739.7 4747.7 4167.6 4291.2 4333.4 4340.6 4347.6 4971.6 4990.1 5003.3 5005.6 5021.7 3680.3 3691.1 3711.4 3718.8 3719.0 5045.1 5243.5 5289.7 5308.7 5317.4 3719.6 3728.4 3729.4 3747.4 3760.8 3792.2 3793.6 3799.4 3800.8 3804.6 4311.0 4316.3 4324.3 4326.9 4332.3 4877.1 5014.4 5023.7 5026.6 5089.9 1295.7 1306.7 1313.3 1319.4 1320.2 852.9 853.9 869.5 876.7 881.0 4339.0 4375.7 4390.3 4467.6 4491.7 5152.7 5171.9 5188.5 5211.4 5232.4 4298.2 4416.9 4440.1 4452.7 4463.8 2736.9 2748.9 2750.9 2752.1 2758.4 4899.4 4905.9 4911.7 4919.8 4928.3 5002.4 5259.9 5264.6 5273.2 5286.5 4865.8 4919.9 4922.8 4930.3 4963.0 5101.8 5134.9 5137.4 5151.6 5199.2 4856.9 5044.5 5068.3 5096.1 5168.4 4955.3 4964.7 4975.9 4981.5 4984.2 3690.3 3710.5 3717.5 3743.6 3745.8 5082.1 5204.4 5297.0 5307.3 5307.6 5053.9 5066.0 5075.5 5079.2 5089.6 5076.7 5080.3 5083.7 5084.7 5091.5 4786.9 5003.3 5007.3 5008.3 5046.3 4869.1 4941.4 5109.0 5115.0 5129.6 4981.3 4993.3 4999.7 5001.2 5013.4 04:32:21 INFO - PROCESS | 3396 | 04:32:21 INFO - PROCESS | 3396 | [#5] yui.html Cycles:180 Average:18340.22 Median:621.18 stddev:27408.33 (4412.3%) stddev-sans-first:27452.91 04:32:21 INFO - PROCESS | 3396 | Values: 616.9 699.5 699.9 730.9 772.8 12779.3 13863.7 14737.8 15394.6 15435.5 15318.5 15329.7 15404.0 15463.3 15497.2 625.4 748.3 785.2 792.1 797.1 795.8 800.5 801.2 801.4 801.5 837.3 839.2 839.2 839.6 839.8 649.6 795.6 818.3 822.1 824.4 603.3 603.6 604.1 604.1 605.2 13506.7 13525.4 13526.4 13559.6 13562.4 11900.2 12214.5 12278.3 12829.7 12903.4 10441.6 10446.5 10504.6 10810.7 11269.5 3366.9 3383.8 3398.8 3492.8 3517.5 62736.0 62994.4 63013.7 63446.0 64157.0 10303.2 10591.6 10649.8 10695.8 10726.1 18726.0 19046.5 19048.7 19070.4 19101.3 6794.5 6961.7 7007.3 7063.2 7199.1 3678.2 3690.9 3706.3 3713.8 3774.9 2904.3 2999.0 3001.4 3002.6 3008.4 475.5 494.4 494.7 503.2 505.1 404.1 407.5 413.5 499.8 531.4 7232.6 7413.9 7474.4 7542.2 7622.9 466.4 487.3 495.4 501.3 516.9 3391.6 3512.8 3543.8 3599.3 3735.8 98781.0 103964.5 105614.0 105871.0 108158.5 88391.0 88684.0 88911.6 89723.1 89813.0 80925.6 82384.0 82415.6 83008.0 83190.0 6979.7 7149.6 7186.2 7188.8 7330.0 48039.5 48657.5 49120.0 50393.7 51469.0 61448.7 69218.3 69949.0 70048.0 71282.6 8449.9 8677.7 8691.2 8711.2 8866.3 10683.4 10858.8 10954.1 11131.9 11255.7 9139.1 9252.3 9305.8 9387.3 9446.2 8897.9 8973.7 9119.6 9136.2 9154.5 8792.3 8981.8 9275.2 9383.5 9399.2 8486.3 8536.7 8975.6 9498.1 9811.8 9241.0 9334.9 9654.3 9664.3 9681.7 04:32:21 INFO - PROCESS | 3396 | -------- Summary: end -------- 04:32:21 INFO - PROCESS | 3396 | 04:32:22 INFO - TEST-INFO | 3396: exit 0 04:32:22 INFO - TEST-OK | dromaeo_css | took 1123004ms 04:32:22 INFO - TEST-START | kraken 04:32:22 INFO - operating with platform_type : linux_ 04:32:22 INFO - Initialising browser for kraken test... 04:32:22 INFO - TEST-INFO | started process 3620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppcCPbZ/profile http://localhost:42444/getInfo.html) 04:32:26 INFO - PROCESS | 3620 | __metrics Screen width/height:1600/1200 04:32:26 INFO - PROCESS | 3620 | colorDepth:24 04:32:26 INFO - PROCESS | 3620 | Browser inner width/height: 1024/697 04:32:26 INFO - PROCESS | 3620 | __metrics 04:32:27 INFO - PROCESS | 3620 | 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" 04:32:27 INFO - PROCESS | 3620 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:28 INFO - TEST-INFO | 3620: exit 0 04:32:28 INFO - Browser initialized. 04:32:28 INFO - Running cycle 1/1 for kraken test... 04:32:28 INFO - TEST-INFO | started process 3699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppcCPbZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:40 INFO - PROCESS | 3699 | RSS: Main: 159019008 04:32:40 INFO - PROCESS | 3699 | 04:32:42 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42444/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:43 INFO - PROCESS | 3699 | RSS: Main: 217133056 04:32:43 INFO - PROCESS | 3699 | 04:32:46 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42444/tests/kraken/driver.html?testName=audio-dft) 04:32:47 INFO - PROCESS | 3699 | RSS: Main: 205041664 04:32:47 INFO - PROCESS | 3699 | 04:32:50 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42444/tests/kraken/driver.html?testName=audio-fft) 04:32:51 INFO - PROCESS | 3699 | RSS: Main: 271093760 04:32:51 INFO - PROCESS | 3699 | 04:32:53 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42444/tests/kraken/driver.html?testName=audio-oscillator) 04:32:53 INFO - PROCESS | 3699 | RSS: Main: 181796864 04:32:53 INFO - PROCESS | 3699 | 04:32:56 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42444/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:32:56 INFO - PROCESS | 3699 | RSS: Main: 186306560 04:32:56 INFO - PROCESS | 3699 | 04:32:59 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42444/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:00 INFO - PROCESS | 3699 | RSS: Main: 222330880 04:33:00 INFO - PROCESS | 3699 | 04:33:03 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42444/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:04 INFO - PROCESS | 3699 | RSS: Main: 222470144 04:33:04 INFO - PROCESS | 3699 | 04:33:07 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42444/tests/kraken/driver.html?testName=json-parse-financial) 04:33:08 INFO - PROCESS | 3699 | RSS: Main: 223268864 04:33:08 INFO - PROCESS | 3699 | 04:33:09 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42444/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:10 INFO - PROCESS | 3699 | RSS: Main: 209444864 04:33:10 INFO - PROCESS | 3699 | 04:33:12 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:12 INFO - PROCESS | 3699 | RSS: Main: 214720512 04:33:12 INFO - PROCESS | 3699 | 04:33:14 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:15 INFO - PROCESS | 3699 | RSS: Main: 212324352 04:33:15 INFO - PROCESS | 3699 | 04:33:18 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:18 INFO - PROCESS | 3699 | RSS: Main: 225005568 04:33:18 INFO - PROCESS | 3699 | 04:33:22 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:22 INFO - PROCESS | 3699 | RSS: Main: 225890304 04:33:22 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:42444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42444/tests/kraken/driver.html?testName=ai-astar) 04:33:24 INFO - PROCESS | 3699 | RSS: Main: 268279808 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | __start_tp_report 04:33:24 INFO - PROCESS | 3699 | _x_x_mozilla_page_load 04:33:24 INFO - PROCESS | 3699 | _x_x_mozilla_page_load_details 04:33:24 INFO - PROCESS | 3699 | |i|pagename|runs| 04:33:24 INFO - PROCESS | 3699 | |0;ai-astar;106;116;113;115;114;114;114;114;114;113 04:33:24 INFO - PROCESS | 3699 | |1;audio-beat-detection;154;122;124;176;132;123;150;126;160;122 04:33:24 INFO - PROCESS | 3699 | |2;audio-dft;160;161;159;161;161;160;161;162;161;162 04:33:24 INFO - PROCESS | 3699 | |3;audio-fft;75;77;77;122;77;79;75;75;97;75 04:33:24 INFO - PROCESS | 3699 | |4;audio-oscillator;85;85;122;85;84;85;131;85;85;123 04:33:24 INFO - PROCESS | 3699 | |5;imaging-gaussian-blur;117;117;116;117;116;116;117;117;117;116 04:33:24 INFO - PROCESS | 3699 | |6;imaging-darkroom;146;148;146;146;145;146;147;146;146;146 04:33:24 INFO - PROCESS | 3699 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 04:33:24 INFO - PROCESS | 3699 | |8;json-parse-financial;65;65;65;64;65;66;65;64;65;65 04:33:24 INFO - PROCESS | 3699 | |9;json-stringify-tinderbox;65;66;67;67;66;65;65;66;66;66 04:33:24 INFO - PROCESS | 3699 | |10;stanford-crypto-aes;79;80;78;79;83;78;79;79;89;82 04:33:24 INFO - PROCESS | 3699 | |11;stanford-crypto-ccm;129;127;127;134;138;128;130;127;168;133 04:33:24 INFO - PROCESS | 3699 | |12;stanford-crypto-pbkdf2;166;165;169;164;163;165;168;167;166;167 04:33:24 INFO - PROCESS | 3699 | |13;stanford-crypto-sha256-iterative;69;67;67;67;70;70;66;70;67;65 04:33:24 INFO - PROCESS | 3699 | __end_tp_report 04:33:24 INFO - PROCESS | 3699 | __start_cc_report 04:33:24 INFO - PROCESS | 3699 | _x_x_mozilla_cycle_collect,1922 04:33:24 INFO - PROCESS | 3699 | __end_cc_report 04:33:24 INFO - PROCESS | 3699 | __startTimestamp1468496004441__endTimestamp 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | ------- Summary: start ------- 04:33:24 INFO - PROCESS | 3699 | Number of tests: 14 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.00 stddev:2.71 (2.4%) stddev-sans-first:0.93 04:33:24 INFO - PROCESS | 3699 | Values: 106.0 116.0 113.0 115.0 114.0 114.0 114.0 114.0 114.0 113.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:141.00 stddev:19.53 (13.9%) stddev-sans-first:19.94 04:33:24 INFO - PROCESS | 3699 | Values: 154.0 122.0 124.0 176.0 132.0 123.0 150.0 126.0 160.0 122.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 04:33:24 INFO - PROCESS | 3699 | Values: 160.0 161.0 159.0 161.0 161.0 160.0 161.0 162.0 161.0 162.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#3] audio-fft Cycles:10 Average:82.90 Median:77.00 stddev:15.26 (19.8%) stddev-sans-first:15.92 04:33:24 INFO - PROCESS | 3699 | Values: 75.0 77.0 77.0 122.0 77.0 79.0 75.0 75.0 97.0 75.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:85.00 stddev:19.69 (23.2%) stddev-sans-first:20.40 04:33:24 INFO - PROCESS | 3699 | Values: 85.0 85.0 122.0 85.0 84.0 85.0 131.0 85.0 85.0 123.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:33:24 INFO - PROCESS | 3699 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 04:33:24 INFO - PROCESS | 3699 | Values: 146.0 148.0 146.0 146.0 145.0 146.0 147.0 146.0 146.0 146.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 04:33:24 INFO - PROCESS | 3699 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 04:33:24 INFO - PROCESS | 3699 | Values: 65.0 65.0 65.0 64.0 65.0 66.0 65.0 64.0 65.0 65.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 04:33:24 INFO - PROCESS | 3699 | Values: 65.0 66.0 67.0 67.0 66.0 65.0 65.0 66.0 66.0 66.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:79.50 stddev:3.37 (4.2%) stddev-sans-first:3.53 04:33:24 INFO - PROCESS | 3699 | Values: 79.0 80.0 78.0 79.0 83.0 78.0 79.0 79.0 89.0 82.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:131.50 stddev:12.46 (9.5%) stddev-sans-first:13.08 04:33:24 INFO - PROCESS | 3699 | Values: 129.0 127.0 127.0 134.0 138.0 128.0 130.0 127.0 168.0 133.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.50 stddev:1.83 (1.1%) stddev-sans-first:1.94 04:33:24 INFO - PROCESS | 3699 | Values: 166.0 165.0 169.0 164.0 163.0 165.0 168.0 167.0 166.0 167.0 04:33:24 INFO - PROCESS | 3699 | 04:33:24 INFO - PROCESS | 3699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.81 (2.7%) stddev-sans-first:1.87 04:33:24 INFO - PROCESS | 3699 | Values: 69.0 67.0 67.0 67.0 70.0 70.0 66.0 70.0 67.0 65.0 04:33:24 INFO - PROCESS | 3699 | -------- Summary: end -------- 04:33:24 INFO - PROCESS | 3699 | 04:33:25 INFO - TEST-INFO | 3699: exit 0 04:33:25 INFO - TEST-OK | kraken | took 63284ms 04:33:25 INFO - SUITE-END | took 1186s 04:33:25 INFO - Completed test suite (00:19:46) 04:33:25 INFO - javascript benchmark 04:33:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2630.2635262126605, 2642.2865826226907, 2673.024346113668, 2675.8126931114816, 2682.369643133863, 4569.702969306993, 4668.6731928765, 4683.859444303084, 4748.074125545516, 4796.184648609736, 4626.606738427237, 4631.837885673994, 4633.467151277604, 4670.136024835408, 4689.132510485562, 4062.573429789874, 4234.428352172464, 4302.247106756321, 4331.870043898688, 4399.340098985146, 4326.30779075348, 4354.7604881731495, 4369.606735393814, 4377.168338015783, 4402.1195760847795, 4194.0, 4232.217039847638, 4237.385579091015, 4263.104748002923, 4319.589638984291, 4255.255330317213, 4265.466816647919, 4279.229738647043, 4294.742315461082, 4327.285997810358, 4242.130363275521, 4288.69979101463, 4293.892652683677, 4298.26929421999, 4336.522982471925, 4493.348464472666, 4661.394018777572, 4688.906221875543, 4706.811727530895, 4720.126776546315, 4984.526469985346, 5059.367579052619, 5099.87250318745, 5120.564751996085, 5181.844544663666, 5132.692038477666, 5151.04705629456, 5189.0, 5206.973965130188, 5212.582993360522, 4573.565511276423, 4651.720896746138, 4810.302506136661, 4855.393075865517, 4868.440129385095, 5120.308758317579, 5125.436202017775, 5126.794928202905, 5162.406323272797, 5170.870728231749, 4941.308216849644, 4985.351904252424, 4990.90018199634, 4994.625403094782, 4995.100881841231, 4849.975750121297, 4879.1461494238365, 4895.534924182268, 5015.3730783652045, 5025.422076461181, 4891.657583969088, 4907.926500807981, 4909.0, 4911.042346742351, 4950.059488697136, 4935.0, 4935.062338155739, 4935.111679897581, 4938.975305123523, 4974.0797952378925, 4076.7553946763287, 4119.8970025748995, 4126.174765047002, 4153.335466325434, 4244.193603215379, 3672.2839046385698, 3681.4293784463453, 3696.685781708531, 3726.552813662324, 3747.138158223624, 4285.61429471349, 4285.8285668573535, 4289.892752681146, 4323.48118225815, 4331.978340108342, 4810.759462026955, 4904.386951631046, 4973.055119527278, 4973.254011898244, 5044.2937988681615, 4265.7014009019695, 4288.24955632763, 4292.527821939584, 4295.742255464682, 4337.457817772778, 4403.207422663952, 4442.222611043055, 4446.866594002185, 4486.371907932892, 4486.7083639563325, 4511.0, 4623.86128416148, 4739.668223224479, 4802.8799280018275, 4812.302216178565, 5041.470645582308, 5046.940142570102, 5055.317532133115, 5069.670471419346, 5115.769790359517, 4593.908121837611, 4790.329353890388, 4804.039192161512, 4805.918668299604, 4852.223644216909, 4938.160512712776, 4975.0, 5032.899342013213, 5051.797928082836, 5054.2671312660195, 5075.264086707333, 5081.110805609078, 5104.132297509358, 5114.974425127851, 5164.04465173944, 4658.697184682985, 4816.8554943351755, 4951.77717002743, 4959.305697202467, 4961.404631444249, 4820.0, 4870.756462176948, 4884.194107972145, 4885.389326334208, 4931.6301277405055, 4805.063012712488, 4805.087033463631, 4832.63755218353, 4841.903161936812, 4846.17615005457, 4746.715197088186, 4788.7366194859615, 4896.681715688469, 4902.044101400193, 4917.557419832232, 4834.057358814997, 4843.0, 4862.7325497098, 4866.0, 4937.506249375034], "value": 4599.917996053142, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.273670527394, 566.3826429192179, 584.9678267695275, 585.6808039618408, 600.2137200267648, 37979.24041519168, 39000.609993900085, 39065.41401878971, 39353.40969885454, 43428.0, 40880.38679419811, 41422.792886035524, 41427.0, 41427.79286103573, 41482.58517414825, 7679.500832445901, 7898.723544675938, 8034.959825200865, 10121.08910198082, 10382.221333399988, 10144.644937427192, 10238.590456381737, 10241.436720980342, 10243.129334006619, 10244.436555989418, 10313.535890884928, 10342.172626189924, 10363.222758293157, 10367.688969330931, 10369.948150259275, 7736.497127686683, 10168.898311016907, 10390.116840068604, 10399.948000260027, 10747.83878241827, 10160.847587286196, 10257.384556926609, 10263.486825658687, 10265.743356416075, 10268.357062869905, 29745.25636859096, 38968.220635587415, 39369.0, 39377.40933885994, 39449.21101577981, 2869.383676175195, 2964.7035296470394, 3043.5891154694054, 3101.503759398508, 3176.857041433141, 27020.729792702215, 27489.587656185173, 30423.23941901448, 30482.08553743391, 31044.689553104407, 2991.641003079644, 2992.8054676445963, 3019.064090132066, 3050.5424186372, 3084.0, 264527.0, 265989.0, 269523.0, 270534.0, 270814.0, 48304.27543586849, 56819.0, 57469.42530574641, 57502.7124864373, 57573.13640295399, 56235.0, 57257.71371143118, 57277.0, 64803.0, 65381.67309163519, 12933.482660693655, 14348.641283967985, 14624.780628290584, 14664.926675366554, 14673.633159170893, 9835.262355323379, 10745.785084298426, 11043.889561104446, 11046.33721976684, 11374.260673056226, 19048.90475547641, 19058.14238359277, 19115.36808006502, 19145.13846876894, 19147.13837877299, 7218.314260145278, 7288.234735352818, 7320.853582928418, 7379.631018449056, 7440.888386674204, 810.7648781853196, 885.8848349714495, 897.6858099665195, 929.1173385284008, 952.6951375559893, 26973.86513067422, 27368.45263094727, 28029.859850701025, 28326.575101373495, 28456.0, 952.2810278240023, 980.9264305176997, 993.438707130469, 1064.3135177810268, 1081.4106312059946, 14571.0, 14940.626484337978, 14980.475683351031, 15022.624434389229, 15237.238138093051, 396651.0, 427664.0, 435024.0, 436359.0, 660319.0, 50539.74730126326, 51031.744841275555, 51236.74381628143, 51256.74371628118, 51335.0, 1088.7931293054294, 1179.4633441783797, 1182.438341787661, 1190.190670344157, 1193.2959553863263, 27385.0, 29146.854265728536, 29150.0, 29844.253893652833, 29943.70056299409, 42257.366139508544, 42939.141217176104, 42990.78504607582, 42996.14007719891, 43014.0, 306619.0, 313182.0, 313886.0, 314616.0, 315722.0, 22304.21935232259, 24414.63378049295, 24788.628170577816, 25467.0, 25827.483450330514, 41430.585694142675, 41714.58285417107, 41925.79037104917, 42164.156716864876, 42198.57801422069, 36239.0, 36586.0, 36595.63404365922, 36722.63277367299, 36923.630763693094, 35707.0, 36686.082847929014, 36751.632483674824, 36815.63184368122, 37232.81383593173, 35485.290294194485, 35516.467252991744, 35822.8208858954, 36201.45697814482, 36616.0, 31559.144077607227, 31960.520592191602, 32074.358512829145, 32700.83649581737, 32724.50913236351, 25143.245702628952, 25427.0, 25552.0, 25622.743772562037, 25976.090836820622], "value": 18325.567460092872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.4955764583711, 382.7187017542106, 384.1491097219659, 385.2449199568845, 399.63034193371124, 12412.131150819447, 16648.833511664896, 17119.40082097127, 17274.0, 17415.21631526581, 16416.09711465869, 16952.0, 17332.913335433335, 17554.473365799015, 17705.645887082283, 1403.6982048859493, 1410.9576712698636, 1413.1238632048094, 1639.0821140161547, 5299.947000529984, 5550.306211723535, 5571.888562228753, 5656.038473459501, 5676.886462270752, 5678.517326027272, 5599.888002239953, 5600.18797274395, 5602.915956260659, 5604.439556044404, 6185.907211391833, 6186.659733714644, 6211.0, 6243.375662433766, 6273.874522509547, 6276.372362763733, 5308.6549374290935, 5521.917171242435, 5531.7234138293325, 5535.916961245584, 5540.836424350891, 13074.477020919152, 13162.802557961639, 13163.605091847165, 15940.840591594051, 22024.0, 1688.7129188038064, 1766.620176662011, 1786.0712429536568, 1788.1416919878402, 1832.5968286976977, 9497.762555936088, 9847.261455390802, 13317.735984882593, 13458.798118028237, 14129.576112716635, 1739.2086600596824, 1764.1443899708631, 1787.865910056738, 1812.4562631210715, 1837.4946889605333, 67147.66426167887, 68366.31633683649, 71495.28504714939, 71974.28025719781, 72918.0, 8952.68665596701, 9609.423434593948, 10377.273590848568, 10383.792324153475, 10491.475426228659, 12371.75256494865, 13371.33143342829, 13391.933040334736, 13669.248191349372, 13760.105593136414, 6850.725970961205, 7126.501144919909, 7203.387712044535, 7216.494845360774, 7252.5648461092505, 6292.433680968735, 6300.495960323163, 6377.4579160771855, 6774.085498457645, 6934.687939042654, 3904.750479846479, 3990.660793832557, 3997.6601988831276, 4012.879613611596, 4158.251514727378, 1848.879822811513, 1863.18951256204, 1863.413024897153, 1883.8587105967108, 1910.5987742574216, 736.6795443981778, 847.2882778466118, 858.1547176031604, 884.8053428245776, 894.49461054504, 17359.045252511234, 17410.564735881704, 17764.46706598804, 18270.817291827178, 18516.907415462836, 810.278851821879, 812.3907069697726, 815.8286759780394, 844.5861527851431, 871.2550769092461, 9708.564009968288, 10036.397816131055, 10060.899391006142, 10061.849072263776, 10161.695149145537, 345346.0, 350874.0, 351182.0, 358424.0, 362645.0, 77287.0, 77709.61145194237, 79698.6015069921, 79847.60076199583, 81376.59311703523, 807.5518087461516, 816.31429599137, 830.2030051150842, 836.122071824594, 841.1420351241821, 15423.61440963985, 15817.130057846929, 15827.129507877178, 15864.603384915008, 16465.25906334194, 26133.73866261313, 26827.999999999218, 37586.436203456426, 38914.0, 39014.804925975186, 48550.27174592455, 48687.26969095537, 49210.75394623004, 49476.0, 49677.503224968736, 7279.162896266997, 7571.242875712384, 7588.468807183444, 7665.6550455228535, 7704.768856934077, 10605.41670208146, 11279.913216055056, 11405.144614154138, 11474.139439542234, 11544.19190656646, 9743.9025609743, 9754.219662427131, 9757.658481953098, 10014.899851001397, 10126.493675316353, 9873.950630246803, 9900.45547494866, 10046.196304295785, 10142.898571014195, 10169.644062458074, 9806.166475849348, 10090.747731306776, 10226.437545935045, 10304.433256170683, 10742.0, 7340.669669864762, 7564.092308922965, 7669.9616501917135, 7713.151553329241, 7744.5740484271655, 9175.174234318943, 9728.17310528613, 9948.651797187063, 9994.0, 10183.0], "value": 7940.509626066925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.870432308931, 1409.0488919979016, 1474.5723740115368, 1704.4716137997223, 1725.8619310455165, 7784.883226751596, 8220.506769593829, 8282.958585207072, 8287.83424331513, 8288.751337459871, 6692.130023097003, 7674.0, 7997.080335761375, 8009.319207867338, 8159.592020398986, 1902.0, 1925.210663627906, 1926.5472613935715, 1928.980710192894, 1932.352661858272, 1883.9717404238947, 2230.7323121225395, 2234.597772400967, 2253.876036817974, 2267.1724820440586, 2113.123053932616, 2190.386691726319, 2201.207565276507, 2212.9114835406563, 2217.5010622609943, 2185.3225500094863, 2198.373463562885, 2207.724034495688, 2216.656418255173, 2218.9334319970426, 2119.3747844385944, 2127.425595089317, 2127.914883404662, 2128.957420851574, 2139.0695047654162, 8058.1538938411795, 8142.592870356459, 8157.388195885273, 8163.836723265561, 8170.1829817018415, 4551.294549344857, 4678.274867395559, 4679.438467383901, 4685.226937555333, 4686.507916668735, 2665.8800353984116, 2718.5319927239266, 2751.0233866977137, 2773.486904922596, 2773.861306934665, 830.7009476588423, 833.6623667414707, 834.1866679987024, 843.9535825529593, 844.4426678392293, 11194.776104477945, 11208.103351731841, 11229.887701122965, 11249.493772780244, 11259.887401125965, 4000.3799411091327, 4112.074783173822, 4237.237297286456, 4240.597143271384, 4267.445232119867, 9382.48396338208, 9435.858462123073, 9458.95270523643, 9459.574319155654, 9466.526673666427, 4585.724856508554, 4597.195490789099, 4597.241455159861, 4599.4710608280475, 4604.953950460453, 4059.7970101494807, 4123.4021066945725, 4133.04939863827, 4138.048248902769, 4141.316682747373, 3766.6798322142927, 3803.6576708096404, 3814.7901865397116, 3851.8844434667003, 3883.572806991229, 1067.9466026698633, 1151.976960460803, 1153.4636394076795, 1189.1497579230718, 1190.880911908802, 817.3502065857539, 821.5112008355019, 829.4732844643695, 840.495702578448, 851.3699862102, 5109.733372633892, 5212.40057393397, 5238.92141617876, 5262.92105618416, 5308.362996440452, 858.0861382627481, 858.3433673239977, 888.200619442494, 904.9638014479347, 914.4833169259347, 3675.632436756303, 3753.1930634913624, 3814.2180852925085, 3884.9223015539533, 3951.9012024699614, 9759.560819763128, 10126.594936202617, 10225.642102526375, 10229.897701022894, 10231.800162964999, 9405.247580193569, 10124.59501619927, 10129.442880641636, 10138.797224055477, 10142.0, 7418.777436676908, 7765.961170194226, 7788.065432148179, 7789.766307010798, 7793.649285782153, 5273.499017593323, 5374.811881584125, 5444.564434845123, 5510.201020852034, 5553.694546799965, 8838.292936565187, 9268.675596354095, 9269.397489163182, 9323.580438880132, 9369.344145909721, 9225.169734723908, 9327.486988215713, 9400.95299523498, 9649.710508684751, 9771.364861283993, 6824.965875170592, 6912.308769123048, 6927.3419025192525, 6970.23327433992, 6979.267176946348, 7688.308052275322, 7857.764267071997, 7876.8818467721885, 7880.05439347282, 7880.56656883854, 5285.550728187994, 6909.619970901648, 7021.192562855337, 7137.928620713727, 7206.855862882818, 6818.215905170969, 6846.897296540656, 6860.794176174722, 7145.642717863982, 7231.783046508614, 6693.799186024427, 6712.362325579063, 6777.593344399352, 6782.152230971129, 6782.1861376635115, 4429.059557391989, 4558.6581006423985, 4589.977050114728, 4678.415198100238, 4707.741074240949, 4690.882727931829, 4694.835680751157, 4742.928856067093, 4903.754812259444, 4918.508149185053], "value": 4199.7002866550465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.30010974758056, 307.86761692472226, 310.1950438611803, 312.89518011466157, 314.48581569134456, 4858.34412354332, 4900.191468407717, 4910.828121015765, 4919.212925931852, 4930.482299358569, 4770.928436073453, 4787.0, 4816.807327706896, 4841.709497430156, 4857.174280372336, 3629.364861149308, 3719.293334266494, 3719.758215715983, 3811.1234416084317, 3818.522684664417, 3756.3989761638145, 3801.8289176986964, 3818.713596480261, 3825.6036546660407, 3839.1361943562756, 3785.5268091488565, 3785.886423407302, 3794.563625183098, 3803.8098095095274, 3807.6863482098634, 3558.5195998540125, 3652.6164752700847, 3799.3920972644246, 3806.54321481424, 3811.3711237645757, 3364.52896594477, 3366.545516355285, 3373.9156521086925, 3376.9831150844334, 3377.5946886373545, 4737.289406589005, 4738.715677059353, 4738.914877453909, 4739.715617062952, 4747.7388743619085, 4167.5832416758385, 4291.163223171483, 4333.371661109153, 4340.565943405666, 4347.5652434756585, 4971.577415919651, 4990.101781679296, 5003.324551185579, 5005.599552035865, 5021.748912554358, 3680.300742858875, 3691.0956815580084, 3711.3876210425246, 3718.7768733875782, 3718.9628103718887, 5045.142325804622, 5243.475652434802, 5289.656172348824, 5308.6549374290935, 5317.388500322474, 3719.628037196313, 3728.422094575345, 3729.366007778684, 3747.362948298796, 3760.7555508891833, 3792.1657235408165, 3793.5827059023486, 3799.3920972644796, 3800.809959502014, 3804.6195380462286, 4311.0300182458695, 4316.330968699856, 4324.2648749712625, 4326.870193894189, 4332.285172946492, 4877.146499362598, 5014.423341315723, 5023.723695196799, 5026.572741316956, 5089.949100509021, 1295.6825577733516, 1306.7190554030929, 1313.2973858985397, 1319.3667039820748, 1320.221069568959, 852.9147085291421, 853.9018012928468, 869.5261082709939, 876.7106854737921, 880.9647614095493, 4338.982373196479, 4375.693701440932, 4390.297552391602, 4467.642588592905, 4491.708038977456, 5152.6650767699975, 5171.948280517222, 5188.481151884856, 5211.374635043819, 5232.39827419844, 4298.183345164472, 4416.889577760582, 4440.067585806952, 4452.666050046259, 4463.843765468193, 2736.86315684215, 2748.9037883674378, 2750.8624568771484, 2752.1330780803983, 2758.4207316463367, 4899.412070551557, 4905.877353066202, 4911.7298548580175, 4919.7540122993705, 4928.334674818853, 5002.424721157041, 5259.896041583321, 5264.578833693296, 5273.156294992783, 5286.471352864683, 4865.783554111472, 4919.90160196794, 4922.75386230687, 4930.309756634146, 4963.0, 5101.79592816298, 5134.9486505135965, 5137.383513978347, 5151.613628977917, 5199.154300223367, 4856.927146092741, 5044.47033061538, 5068.2904393384215, 5096.0827051131155, 5168.3797944245425, 4955.2567114933045, 4964.67729597575, 4975.875603109951, 4981.452040275494, 4984.22744474607, 3690.3357395667963, 3710.4990826238113, 3717.4795528625473, 3743.5694895086326, 3745.8314375853697, 5082.136036873814, 5204.401493828258, 5296.973515132554, 5307.283516725192, 5307.6284660073425, 5053.924191137063, 5065.974670126626, 5075.492450754895, 5079.16193828029, 5089.618278629192, 5076.670016448919, 5080.323493247146, 5083.72039537829, 5084.728817795552, 5091.516305950929, 4786.856394308176, 5003.34956455673, 5007.3240112584335, 5008.323876276656, 5046.34397528319, 4869.099216644932, 4941.357623508921, 5108.974455127701, 5114.95143495575, 5129.615278854027, 4981.334985782589, 4993.3259010034635, 4999.650024498395, 5001.174806157089, 5013.423456302572], "value": 3828.900910581023, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.9290531588867, 699.506847672391, 699.8950157476374, 730.9328380564376, 772.8338407242444, 12779.29713865737, 13863.722725545484, 14737.778933316009, 15394.61513462164, 15435.536933891974, 15318.46385376512, 15329.693406131899, 15404.0, 15463.30415131319, 15497.225138743073, 625.4308579192935, 748.3377211168101, 785.1598789295456, 792.13657113746, 797.0554892452462, 795.7652492514693, 800.5396896784343, 801.2388231180373, 801.3749275565068, 801.4670244287541, 837.3092198935872, 839.1901814748766, 839.2488722593275, 839.6347588798892, 839.8278352937664, 649.5680372552247, 795.5942469340649, 818.3371469109981, 822.0874828939872, 824.4435006370736, 603.3393434189555, 603.5654328883197, 604.114971566657, 604.1210039392673, 605.1799811255729, 13506.729865402735, 13525.39135738894, 13526.391312390966, 13559.59321220365, 13562.389692463863, 11900.166988310822, 12214.450349734194, 12278.263304201688, 12829.67925801853, 12903.35483225835, 10441.582336706522, 10446.529906154241, 10504.632337868214, 10810.72973175669, 11269.549218031269, 3366.882159124443, 3383.813890236036, 3398.762086653935, 3492.755507114503, 3517.4723791431234, 62736.0, 62994.37005629931, 63013.68493157505, 63446.0, 64157.0, 10303.227257955534, 10591.576336946437, 10649.787004259872, 10695.83956240657, 10726.141908647289, 18726.0, 19046.523836904187, 19048.71426928597, 19070.42788716313, 19101.331453399063, 6794.524383293221, 6961.721531138698, 7007.299270072952, 7063.1524217094275, 7199.1361036675935, 3678.2275722098657, 3690.8892733218045, 3706.258748250307, 3713.814309284579, 3774.867879624255, 2904.3320036391747, 2998.9850050749606, 3001.4147241287737, 3002.579638850563, 3008.368242669064, 475.46272711835405, 494.37708487306253, 494.675987228366, 503.17228159677126, 505.0505050505027, 404.0706375336714, 407.5089517131908, 413.4769516561552, 499.8475464983215, 531.4472948133974, 7232.566046037255, 7413.925860741431, 7474.439417043744, 7542.208068152765, 7622.885656715153, 466.44493053266507, 487.2520680755095, 495.4327295246922, 501.29567957020447, 516.880952737327, 3391.6099648540735, 3512.7892326460496, 3543.8050907199836, 3599.298136863339, 3735.831887565066, 98781.0, 103964.48017760014, 105614.0, 105871.0, 108158.45920770503, 88391.0, 88684.0, 88911.55544222367, 89723.10276897147, 89813.0, 80925.59537202277, 82384.0, 82415.58792206, 83008.0, 83190.0, 6979.651017449006, 7149.571025738473, 7186.173590037212, 7188.820279493055, 7330.0, 48039.51960480351, 48657.5134248653, 49120.0, 50393.74803126108, 51469.0, 61448.69275653772, 69218.30781692118, 69949.0, 70048.0, 71282.64358678173, 8449.915500845158, 8677.739667809976, 8691.217790398894, 8711.172438618321, 8866.290696744374, 10683.412412317397, 10858.837117443087, 10954.068904143238, 11131.944340278247, 11255.662330129782, 9139.086091390807, 9252.259819214312, 9305.767355816159, 9387.295952803704, 9446.24430045581, 8897.911020889967, 8973.730788076107, 9119.594992891392, 9136.177744003071, 9154.450732956044, 8792.296616270554, 8981.775455613662, 9275.211607013209, 9383.530823458936, 9399.201067909304, 8486.278666313432, 8536.658533658585, 8975.640974361213, 9498.071419683962, 9811.803763924825, 9240.95379523098, 9334.906650933404, 9654.27592930519, 9664.275179361717, 9681.70954871355], "value": 6349.736313317396, "unit": "score"}], "name": "dromaeo_css", "value": 6394.291345018543, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 113.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 124.0, 176.0, 132.0, 123.0, 150.0, 126.0, 160.0, 122.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 161.0, 161.0, 160.0, 161.0, 162.0, 161.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 122.0, 77.0, 79.0, 75.0, 75.0, 97.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 122.0, 85.0, 84.0, 85.0, 131.0, 85.0, 85.0, 123.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 66.0, 65.0, 64.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 67.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 78.0, 79.0, 83.0, 78.0, 79.0, 79.0, 89.0, 82.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 127.0, 127.0, 134.0, 138.0, 128.0, 130.0, 127.0, 168.0, 133.0], "value": 134.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 169.0, 164.0, 163.0, 165.0, 168.0, 167.0, 166.0, 167.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 67.0, 70.0, 70.0, 66.0, 70.0, 67.0, 65.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1530.1, "alertThreshold": 2.0}]} 04:33:25 INFO - Return code: 0 04:33:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:25 INFO - # TBPL SUCCESS # 04:33:25 INFO - Running post-action listener: _resource_record_post_action 04:33:25 INFO - ##### 04:33:25 INFO - ##### Finished run-tests step (success) 04:33:25 INFO - ##### 04:33:25 INFO - Running post-run listener: _resource_record_post_run 04:33:25 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 10141696; Write bytes: 507867136; Read time: 2452; Write time: 492572 04:33:25 INFO - TinderboxPrint: CPU usage
13.0% 04:33:25 INFO - TinderboxPrint: I/O read bytes / time
10,141,696 / 2,452 04:33:25 INFO - TinderboxPrint: I/O write bytes / time
507,867,136 / 492,572 04:33:25 INFO - TinderboxPrint: CPU idle
8,355.9 (87.0%) 04:33:25 INFO - TinderboxPrint: CPU user
1,201.0 (12.5%) 04:33:25 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 70377472; Read time: 0; Write time: 52900 04:33:25 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 8400896; Write bytes: 435675136; Read time: 2240; Write time: 439376 04:33:26 INFO - Running post-run listener: _upload_blobber_files 04:33:26 INFO - Blob upload gear active. 04:33:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:26 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 04:33:26 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'] 04:33:26 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 04:33:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:41 INFO - (blobuploader) - INFO - Done attempting. 04:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:41 INFO - (blobuploader) - INFO - Done attempting. 04:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:42 INFO - (blobuploader) - INFO - Done attempting. 04:33:42 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:42 INFO - Return code: 0 04:33:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4e3a5544f7ecc03503afbfd9622dd86f89bc699f208a08371ee063fc7f0a15dc338716a4bd97ef0296ef71d468da1be904bdc3070dd97190d3559ecdd44977b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0000b495ceddf0f94bccc7b1cf6c2b7dbd662d46d2a22f124536df7159a3080a352c4bd5ac1e99a933c28add4b428a2d9f05ade5ff305353ffb8a3ffb6959c70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6ce3c7d47d945a6273e8208035205e65baa90d922d3dc875c9bdf1109dcc42d6cdb972b2af6515459eff2b2d547c484debbe29a9bcd548dc41d90867f01fbb48"} 04:33:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:42 INFO - Contents: 04:33:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4e3a5544f7ecc03503afbfd9622dd86f89bc699f208a08371ee063fc7f0a15dc338716a4bd97ef0296ef71d468da1be904bdc3070dd97190d3559ecdd44977b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0000b495ceddf0f94bccc7b1cf6c2b7dbd662d46d2a22f124536df7159a3080a352c4bd5ac1e99a933c28add4b428a2d9f05ade5ff305353ffb8a3ffb6959c70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6ce3c7d47d945a6273e8208035205e65baa90d922d3dc875c9bdf1109dcc42d6cdb972b2af6515459eff2b2d547c484debbe29a9bcd548dc41d90867f01fbb48"} 04:33:42 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:42 INFO - Copying logs to upload dir... 04:33:42 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.556629 ========= master_lag: -0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-07-14 04:33:42.641622) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 04:33:42.645456) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361 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/4e3a5544f7ecc03503afbfd9622dd86f89bc699f208a08371ee063fc7f0a15dc338716a4bd97ef0296ef71d468da1be904bdc3070dd97190d3559ecdd44977b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0000b495ceddf0f94bccc7b1cf6c2b7dbd662d46d2a22f124536df7159a3080a352c4bd5ac1e99a933c28add4b428a2d9f05ade5ff305353ffb8a3ffb6959c70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6ce3c7d47d945a6273e8208035205e65baa90d922d3dc875c9bdf1109dcc42d6cdb972b2af6515459eff2b2d547c484debbe29a9bcd548dc41d90867f01fbb48"} build_url:https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/DLOiQzpaS4iGalTKZiHcEQ/artifacts/public/build/firefox-50.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/4e3a5544f7ecc03503afbfd9622dd86f89bc699f208a08371ee063fc7f0a15dc338716a4bd97ef0296ef71d468da1be904bdc3070dd97190d3559ecdd44977b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0000b495ceddf0f94bccc7b1cf6c2b7dbd662d46d2a22f124536df7159a3080a352c4bd5ac1e99a933c28add4b428a2d9f05ade5ff305353ffb8a3ffb6959c70", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6ce3c7d47d945a6273e8208035205e65baa90d922d3dc875c9bdf1109dcc42d6cdb972b2af6515459eff2b2d547c484debbe29a9bcd548dc41d90867f01fbb48"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 04:33:42.670307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:33:42.670613) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468494724.715407-1312797361 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004366 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:33:42.755889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 04:33:42.756338) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 04:33:42.756610) ========= ========= Total master_lag: 0.03 =========