builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1468960261.03 results: success (0) buildid: 20160719121221 builduid: bdb5eb29b7584103aaca80023d6ce5c3 revision: 2c04758cf3544a406711b01df129b04dd5376cf3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.027059) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.027467) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.027736) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.343323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.343589) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 13:31:01-- 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% 46.5M=0s 2016-07-19 13:31:01 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.447731) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.448063) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014815 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.507440) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:01.507826) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2c04758cf3544a406711b01df129b04dd5376cf3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 2c04758cf3544a406711b01df129b04dd5376cf3 --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=56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 13:31:01,635 truncating revision to first 12 chars 2016-07-19 13:31:01,635 Setting DEBUG logging. 2016-07-19 13:31:01,635 attempt 1/10 2016-07-19 13:31:01,635 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2c04758cf354?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 13:31:01,966 unpacking tar archive at: autoland-2c04758cf354/testing/mozharness/ program finished with exit code 0 elapsedTime=0.652233 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:02.174525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:02.174830) ========= script_repo_revision: 2c04758cf3544a406711b01df129b04dd5376cf3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:02.175356) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:02.175621) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 13:31:02.189434) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-19 13:31:02.189703) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:31:02 INFO - MultiFileLogger online at 20160719 13:31:02 in /builds/slave/test 13:31:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 13:31:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:31:02 INFO - {'append_to_log': False, 13:31:02 INFO - 'base_work_dir': '/builds/slave/test', 13:31:02 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 13:31:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:31:02 INFO - 'branch': 'Autoland-Non-PGO', 13:31:02 INFO - 'buildbot_json_path': 'buildprops.json', 13:31:02 INFO - 'config_files': ('talos/linux_config.py',), 13:31:02 INFO - 'default_actions': ('clobber', 13:31:02 INFO - 'read-buildbot-config', 13:31:02 INFO - 'download-and-extract', 13:31:02 INFO - 'populate-webroot', 13:31:02 INFO - 'create-virtualenv', 13:31:02 INFO - 'install', 13:31:02 INFO - 'run-tests'), 13:31:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:31:02 INFO - 'download_minidump_stackwalk': True, 13:31:02 INFO - 'download_symbols': 'ondemand', 13:31:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:31:02 INFO - 'tooltool.py': '/tools/tooltool.py', 13:31:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:31:02 INFO - '/tools/misc-python/virtualenv.py')}, 13:31:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:31:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:31:02 INFO - 'installer_path': 'installer.exe', 13:31:02 INFO - 'log_level': 'info', 13:31:02 INFO - 'log_name': 'talos', 13:31:02 INFO - 'log_to_console': True, 13:31:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:31:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:31:02 INFO - 'opt_config_files': (), 13:31:02 INFO - 'pip_index': False, 13:31:02 INFO - 'sps_profile': False, 13:31:02 INFO - 'sps_profile_interval': 0, 13:31:02 INFO - 'suite': 'dromaeojs', 13:31:02 INFO - 'system_bits': '32', 13:31:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:31:02 INFO - 'title': 'talos-linux64-ix-037', 13:31:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:31:02 INFO - 'use_talos_json': True, 13:31:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:31:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:31:02 INFO - 'work_dir': 'build'} 13:31:02 INFO - [mozharness: 2016-07-19 20:31:02.410643Z] Running clobber step. 13:31:02 INFO - Running pre-action listener: _resource_record_pre_action 13:31:02 INFO - Running main action method: clobber 13:31:02 INFO - rmtree: /builds/slave/test/build 13:31:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:31:03 INFO - Running post-action listener: _resource_record_post_action 13:31:03 INFO - [mozharness: 2016-07-19 20:31:03.396442Z] Finished clobber step (success) 13:31:03 INFO - [mozharness: 2016-07-19 20:31:03.396544Z] Running read-buildbot-config step. 13:31:03 INFO - Running pre-action listener: _resource_record_pre_action 13:31:03 INFO - Running main action method: read_buildbot_config 13:31:03 INFO - Using buildbot properties: 13:31:03 INFO - { 13:31:03 INFO - "project": "", 13:31:03 INFO - "product": "firefox", 13:31:03 INFO - "script_repo_revision": "production", 13:31:03 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 13:31:03 INFO - "repository": "", 13:31:03 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 13:31:03 INFO - "buildid": "20160719121221", 13:31:03 INFO - "pgo_build": "False", 13:31:03 INFO - "basedir": "/builds/slave/test", 13:31:03 INFO - "buildnumber": 172, 13:31:03 INFO - "slavename": "talos-linux64-ix-037", 13:31:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:31:03 INFO - "platform": "ubuntu64_hw", 13:31:03 INFO - "scheduler": "tests-autoland-linux64-talos", 13:31:03 INFO - "branch": "autoland", 13:31:03 INFO - "repo_path": "integration/autoland", 13:31:03 INFO - "revision": "2c04758cf3544a406711b01df129b04dd5376cf3", 13:31:03 INFO - "stage_platform": "linux64", 13:31:03 INFO - "builduid": "bdb5eb29b7584103aaca80023d6ce5c3", 13:31:03 INFO - "slavebuilddir": "test" 13:31:03 INFO - } 13:31:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 13:31:03 INFO - Running post-action listener: _resource_record_post_action 13:31:03 INFO - [mozharness: 2016-07-19 20:31:03.399041Z] Finished read-buildbot-config step (success) 13:31:03 INFO - [mozharness: 2016-07-19 20:31:03.399133Z] Running download-and-extract step. 13:31:03 INFO - Running pre-action listener: _resource_record_pre_action 13:31:03 INFO - Running main action method: download_and_extract 13:31:03 INFO - mkdir: /builds/slave/test/build/tests 13:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:03 INFO - https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:31:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:31:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:31:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:08 INFO - Downloaded 1361 bytes. 13:31:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:31:08 INFO - Using the following test package requirements: 13:31:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:31:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:31:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:31:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'jsshell-linux-x86_64.zip'], 13:31:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:31:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:31:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:31:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:31:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:31:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:31:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:31:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:08 INFO - https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:31:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:31:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:31:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:15 INFO - Downloaded 22765221 bytes. 13:31:15 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'] 13:31:15 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 13:31:16 INFO - Return code: 0 13:31:16 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 13:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:16 INFO - https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:31:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:31:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:31:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:22 INFO - Downloaded 22765221 bytes. 13:31:22 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'] 13:31:22 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 13:31:24 INFO - Return code: 0 13:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:24 INFO - https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:31:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:31:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:31:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:25 INFO - Downloaded 14403317 bytes. 13:31:25 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'] 13:31:25 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 13:31:25 INFO - Return code: 0 13:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:25 INFO - https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:31:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:31:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:31:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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 13:31:27 INFO - Downloaded 57138963 bytes. 13:31:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:31:27 INFO - mkdir: /builds/slave/test/properties 13:31:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:31:27 INFO - Writing to file /builds/slave/test/properties/build_url 13:31:27 INFO - Contents: 13:31:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:31:27 INFO - Running post-action listener: _resource_record_post_action 13:31:27 INFO - Running post-action listener: set_extra_try_arguments 13:31:27 INFO - [mozharness: 2016-07-19 20:31:27.964680Z] Finished download-and-extract step (success) 13:31:27 INFO - [mozharness: 2016-07-19 20:31:27.965007Z] Running populate-webroot step. 13:31:27 INFO - Running pre-action listener: _resource_record_pre_action 13:31:27 INFO - Running main action method: populate_webroot 13:31:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:31:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:31:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:31:27 INFO - '--tppagecycles', 13:31:27 INFO - '7'], 13:31:27 INFO - 'tests': ['tsvgm']}}, 13:31:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:31:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:31:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:31:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:31:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['damp', 'tps']}, 13:31:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'tests': ['damp', 'tps']}, 13:31:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['dromaeo_dom']}, 13:31:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:31:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['basic_compositor_video']}, 13:31:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:31:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['a11yr', 13:31:27 INFO - 'ts_paint', 13:31:27 INFO - 'tpaint', 13:31:27 INFO - 'sessionrestore', 13:31:27 INFO - 'sessionrestore_no_auto_restore', 13:31:27 INFO - 'tabpaint']}, 13:31:27 INFO - 'other-e10s': {'tests': ['a11yr', 13:31:27 INFO - 'ts_paint', 13:31:27 INFO - 'tpaint', 13:31:27 INFO - 'sessionrestore', 13:31:27 INFO - 'sessionrestore_no_auto_restore', 13:31:27 INFO - 'tabpaint']}, 13:31:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:31:27 INFO - 'ts_paint', 13:31:27 INFO - 'tpaint', 13:31:27 INFO - 'sessionrestore', 13:31:27 INFO - 'sessionrestore_no_auto_restore', 13:31:27 INFO - 'tabpaint']}, 13:31:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:31:27 INFO - 'ts_paint', 13:31:27 INFO - 'tpaint', 13:31:27 INFO - 'sessionrestore', 13:31:27 INFO - 'sessionrestore_no_auto_restore', 13:31:27 INFO - 'tabpaint']}, 13:31:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['a11yr', 13:31:27 INFO - 'ts_paint', 13:31:27 INFO - 'tpaint', 13:31:27 INFO - 'sessionrestore', 13:31:27 INFO - 'sessionrestore_no_auto_restore', 13:31:27 INFO - 'tabpaint']}, 13:31:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['a11yr', 13:31:27 INFO - 'ts_paint', 13:31:27 INFO - 'tpaint', 13:31:27 INFO - 'sessionrestore', 13:31:27 INFO - 'sessionrestore_no_auto_restore', 13:31:27 INFO - 'tabpaint']}, 13:31:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['tsvgx', 13:31:27 INFO - 'tsvgr_opacity', 13:31:27 INFO - 'tart', 13:31:27 INFO - 'tscrollx', 13:31:27 INFO - 'cart']}, 13:31:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:31:27 INFO - 'tsvgr_opacity', 13:31:27 INFO - 'tart', 13:31:27 INFO - 'tscrollx', 13:31:27 INFO - 'cart']}, 13:31:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'talos_options': ['--disable-e10s'], 13:31:27 INFO - 'tests': ['tp5o']}, 13:31:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'tests': ['tp5o']}, 13:31:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'talos_options': ['--disable-e10s', 13:31:27 INFO - '--xperf_path', 13:31:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:27 INFO - 'tests': ['tp5n']}, 13:31:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:27 INFO - 'talos_options': ['--xperf_path', 13:31:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:27 INFO - 'tests': ['tp5n']}}, 13:31:27 INFO - 'talos.zip': {'path': '', 13:31:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:31:27 INFO - Running post-action listener: _resource_record_post_action 13:31:27 INFO - [mozharness: 2016-07-19 20:31:27.982459Z] Finished populate-webroot step (success) 13:31:27 INFO - [mozharness: 2016-07-19 20:31:27.982549Z] Running create-virtualenv step. 13:31:27 INFO - Running pre-action listener: _resource_record_pre_action 13:31:27 INFO - Running main action method: create_virtualenv 13:31:27 INFO - Creating virtualenv /builds/slave/test/build/venv 13:31:27 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 13:31:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:31:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:31:28 INFO - Using real prefix '/usr' 13:31:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:31:28 INFO - Installing distribute.............................................................................................................................................................................................done. 13:31:30 INFO - Installing pip.................done. 13:31:30 INFO - Return code: 0 13:31:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:31:30 INFO - Using env: {'DISPLAY': ':0', 13:31:30 INFO - 'HOME': '/home/cltbld', 13:31:30 INFO - 'LANG': 'en_US.UTF-8', 13:31:30 INFO - 'LANGUAGE': 'en_US:en', 13:31:30 INFO - 'LOGNAME': 'cltbld', 13:31:30 INFO - 'MAIL': '/var/mail/cltbld', 13:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:30 INFO - 'MOZ_NO_REMOTE': '1', 13:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:30 INFO - 'NO_EM_RESTART': '1', 13:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:30 INFO - 'PWD': '/builds/slave/test', 13:31:30 INFO - 'SHELL': '/bin/bash', 13:31:30 INFO - 'SHLVL': '1', 13:31:30 INFO - 'TERM': 'linux', 13:31:30 INFO - 'TMOUT': '86400', 13:31:30 INFO - 'USER': 'cltbld', 13:31:30 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:30 INFO - '_': '/tools/buildbot/bin/python'} 13:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:30 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 13:31:30 INFO - Cleaning up... 13:31:30 INFO - Return code: 0 13:31:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:31:30 INFO - Using env: {'DISPLAY': ':0', 13:31:30 INFO - 'HOME': '/home/cltbld', 13:31:30 INFO - 'LANG': 'en_US.UTF-8', 13:31:30 INFO - 'LANGUAGE': 'en_US:en', 13:31:30 INFO - 'LOGNAME': 'cltbld', 13:31:30 INFO - 'MAIL': '/var/mail/cltbld', 13:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:30 INFO - 'MOZ_NO_REMOTE': '1', 13:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:30 INFO - 'NO_EM_RESTART': '1', 13:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:30 INFO - 'PWD': '/builds/slave/test', 13:31:30 INFO - 'SHELL': '/bin/bash', 13:31:30 INFO - 'SHLVL': '1', 13:31:30 INFO - 'TERM': 'linux', 13:31:30 INFO - 'TMOUT': '86400', 13:31:30 INFO - 'USER': 'cltbld', 13:31:30 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:30 INFO - '_': '/tools/buildbot/bin/python'} 13:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:30 INFO - Downloading/unpacking psutil>=3.1.1 13:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:31:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:33 INFO - Installing collected packages: psutil 13:31:33 INFO - Running setup.py install for psutil 13:31:33 INFO - building 'psutil._psutil_linux' extension 13:31:33 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 13:31:33 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 13:31:33 INFO - building 'psutil._psutil_posix' extension 13:31:33 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 13:31:33 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 13:31:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:33 INFO - Successfully installed psutil 13:31:33 INFO - Cleaning up... 13:31:33 INFO - Return code: 0 13:31:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:31:33 INFO - Using env: {'DISPLAY': ':0', 13:31:33 INFO - 'HOME': '/home/cltbld', 13:31:33 INFO - 'LANG': 'en_US.UTF-8', 13:31:33 INFO - 'LANGUAGE': 'en_US:en', 13:31:33 INFO - 'LOGNAME': 'cltbld', 13:31:33 INFO - 'MAIL': '/var/mail/cltbld', 13:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:33 INFO - 'MOZ_NO_REMOTE': '1', 13:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:33 INFO - 'NO_EM_RESTART': '1', 13:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:33 INFO - 'PWD': '/builds/slave/test', 13:31:33 INFO - 'SHELL': '/bin/bash', 13:31:33 INFO - 'SHLVL': '1', 13:31:33 INFO - 'TERM': 'linux', 13:31:33 INFO - 'TMOUT': '86400', 13:31:33 INFO - 'USER': 'cltbld', 13:31:33 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:33 INFO - '_': '/tools/buildbot/bin/python'} 13:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:31:35 INFO - Installing collected packages: mozsystemmonitor 13:31:35 INFO - Running setup.py install for mozsystemmonitor 13:31:35 INFO - Successfully installed mozsystemmonitor 13:31:35 INFO - Cleaning up... 13:31:35 INFO - Return code: 0 13:31:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:31:35 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')]} 13:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:35 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')]} 13:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:35 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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:35 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 13:31:35 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 13:31:35 INFO - Using env: {'DISPLAY': ':0', 13:31:35 INFO - 'HOME': '/home/cltbld', 13:31:35 INFO - 'LANG': 'en_US.UTF-8', 13:31:35 INFO - 'LANGUAGE': 'en_US:en', 13:31:35 INFO - 'LOGNAME': 'cltbld', 13:31:35 INFO - 'MAIL': '/var/mail/cltbld', 13:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:35 INFO - 'MOZ_NO_REMOTE': '1', 13:31:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:35 INFO - 'NO_EM_RESTART': '1', 13:31:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:35 INFO - 'PWD': '/builds/slave/test', 13:31:35 INFO - 'SHELL': '/bin/bash', 13:31:35 INFO - 'SHLVL': '1', 13:31:35 INFO - 'TERM': 'linux', 13:31:35 INFO - 'TMOUT': '86400', 13:31:35 INFO - 'USER': 'cltbld', 13:31:35 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:35 INFO - '_': '/tools/buildbot/bin/python'} 13:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:36 INFO - Downloading/unpacking jsonschema==2.5.1 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:31:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:31:37 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 13:31:37 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 13:31:37 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 13:31:37 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 13:31:37 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 13:31:37 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 13:31:38 INFO - Downloading functools32-3.2.3-2.tar.gz 13:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:31:38 INFO - warning: no files found matching '*.txt' 13:31:38 INFO - no previously-included directories found matching 'build' 13:31:38 INFO - no previously-included directories found matching 'dist' 13:31:38 INFO - no previously-included directories found matching '.git*' 13:31:38 INFO - Installing collected packages: jsonschema, functools32 13:31:38 INFO - Running setup.py install for functools32 13:31:38 INFO - warning: no files found matching '*.txt' 13:31:38 INFO - no previously-included directories found matching 'build' 13:31:38 INFO - no previously-included directories found matching 'dist' 13:31:38 INFO - no previously-included directories found matching '.git*' 13:31:38 INFO - Successfully installed jsonschema functools32 13:31:38 INFO - Cleaning up... 13:31:38 INFO - Return code: 0 13:31:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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')]} 13:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:38 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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:38 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 13:31:38 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 13:31:38 INFO - Using env: {'DISPLAY': ':0', 13:31:38 INFO - 'HOME': '/home/cltbld', 13:31:38 INFO - 'LANG': 'en_US.UTF-8', 13:31:38 INFO - 'LANGUAGE': 'en_US:en', 13:31:38 INFO - 'LOGNAME': 'cltbld', 13:31:38 INFO - 'MAIL': '/var/mail/cltbld', 13:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:38 INFO - 'MOZ_NO_REMOTE': '1', 13:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:38 INFO - 'NO_EM_RESTART': '1', 13:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:38 INFO - 'PWD': '/builds/slave/test', 13:31:38 INFO - 'SHELL': '/bin/bash', 13:31:38 INFO - 'SHLVL': '1', 13:31:38 INFO - 'TERM': 'linux', 13:31:38 INFO - 'TMOUT': '86400', 13:31:38 INFO - 'USER': 'cltbld', 13:31:38 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:38 INFO - '_': '/tools/buildbot/bin/python'} 13:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:38 INFO - Downloading/unpacking blobuploader==1.2.4 13:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:40 INFO - Downloading blobuploader-1.2.4.tar.gz 13:31:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:31:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:31:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:41 INFO - Downloading docopt-0.6.1.tar.gz 13:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:31:41 INFO - Installing collected packages: blobuploader, requests, docopt 13:31:41 INFO - Running setup.py install for blobuploader 13:31:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:31:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:31:41 INFO - Running setup.py install for requests 13:31:41 INFO - Running setup.py install for docopt 13:31:41 INFO - Successfully installed blobuploader requests docopt 13:31:41 INFO - Cleaning up... 13:31:41 INFO - Return code: 0 13:31:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:41 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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:41 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 13:31:41 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 13:31:41 INFO - Using env: {'DISPLAY': ':0', 13:31:41 INFO - 'HOME': '/home/cltbld', 13:31:41 INFO - 'LANG': 'en_US.UTF-8', 13:31:41 INFO - 'LANGUAGE': 'en_US:en', 13:31:41 INFO - 'LOGNAME': 'cltbld', 13:31:41 INFO - 'MAIL': '/var/mail/cltbld', 13:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:41 INFO - 'MOZ_NO_REMOTE': '1', 13:31:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:41 INFO - 'NO_EM_RESTART': '1', 13:31:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:41 INFO - 'PWD': '/builds/slave/test', 13:31:41 INFO - 'SHELL': '/bin/bash', 13:31:41 INFO - 'SHLVL': '1', 13:31:41 INFO - 'TERM': 'linux', 13:31:41 INFO - 'TMOUT': '86400', 13:31:41 INFO - 'USER': 'cltbld', 13:31:41 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:41 INFO - '_': '/tools/buildbot/bin/python'} 13:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:42 INFO - Running setup.py (path:/tmp/pip-As6ws5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:42 INFO - Running setup.py (path:/tmp/pip-jFN9Go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:42 INFO - Running setup.py (path:/tmp/pip-W4ASdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:42 INFO - Running setup.py (path:/tmp/pip-Cos0Lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:42 INFO - Running setup.py (path:/tmp/pip-z7BdqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:42 INFO - Running setup.py (path:/tmp/pip-r5IqYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:42 INFO - Running setup.py (path:/tmp/pip-fBX2Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:42 INFO - Running setup.py (path:/tmp/pip-4kKUs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:42 INFO - Running setup.py (path:/tmp/pip-V9025j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:42 INFO - Running setup.py (path:/tmp/pip-FW6jEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:42 INFO - Running setup.py (path:/tmp/pip-ksTBCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:42 INFO - Running setup.py (path:/tmp/pip-jxe6Jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:43 INFO - Running setup.py (path:/tmp/pip-jV0jY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:43 INFO - Running setup.py (path:/tmp/pip-wu8ipp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:43 INFO - Running setup.py (path:/tmp/pip-BDgzGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:43 INFO - Running setup.py (path:/tmp/pip-i2QJDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:43 INFO - Running setup.py (path:/tmp/pip-WXnHOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:31:43 INFO - Running setup.py install for manifestparser 13:31:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozcrash 13:31:43 INFO - Running setup.py install for mozdebug 13:31:43 INFO - Running setup.py install for mozdevice 13:31:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozfile 13:31:43 INFO - Running setup.py install for mozhttpd 13:31:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozinfo 13:31:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozInstall 13:31:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozleak 13:31:44 INFO - Running setup.py install for mozlog 13:31:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for moznetwork 13:31:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozprocess 13:31:44 INFO - Running setup.py install for mozprofile 13:31:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozrunner 13:31:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozscreenshot 13:31:44 INFO - Running setup.py install for moztest 13:31:45 INFO - Running setup.py install for mozversion 13:31:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:31:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:31:45 INFO - Cleaning up... 13:31:45 INFO - Return code: 0 13:31:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:45 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')]} 13:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:45 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')]} 13:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:45 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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:45 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 13:31:45 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 13:31:45 INFO - Using env: {'DISPLAY': ':0', 13:31:45 INFO - 'HOME': '/home/cltbld', 13:31:45 INFO - 'LANG': 'en_US.UTF-8', 13:31:45 INFO - 'LANGUAGE': 'en_US:en', 13:31:45 INFO - 'LOGNAME': 'cltbld', 13:31:45 INFO - 'MAIL': '/var/mail/cltbld', 13:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:45 INFO - 'MOZ_NO_REMOTE': '1', 13:31:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:45 INFO - 'NO_EM_RESTART': '1', 13:31:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:45 INFO - 'PWD': '/builds/slave/test', 13:31:45 INFO - 'SHELL': '/bin/bash', 13:31:45 INFO - 'SHLVL': '1', 13:31:45 INFO - 'TERM': 'linux', 13:31:45 INFO - 'TMOUT': '86400', 13:31:45 INFO - 'USER': 'cltbld', 13:31:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:45 INFO - '_': '/tools/buildbot/bin/python'} 13:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:45 INFO - Running setup.py (path:/tmp/pip-GOkhrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:45 INFO - Running setup.py (path:/tmp/pip-4TSs0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:45 INFO - Running setup.py (path:/tmp/pip-_NziaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:45 INFO - Running setup.py (path:/tmp/pip-6OykVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:45 INFO - Running setup.py (path:/tmp/pip-NpZStr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:45 INFO - Running setup.py (path:/tmp/pip-Vsxyh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:45 INFO - Running setup.py (path:/tmp/pip-RMpN11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:45 INFO - Running setup.py (path:/tmp/pip-U8SVp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:45 INFO - Running setup.py (path:/tmp/pip-4yn3wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:46 INFO - Running setup.py (path:/tmp/pip-3ziCue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:46 INFO - Running setup.py (path:/tmp/pip-h4DhUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:46 INFO - Running setup.py (path:/tmp/pip-xDQE7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:46 INFO - Running setup.py (path:/tmp/pip-paKUHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:46 INFO - Running setup.py (path:/tmp/pip-cInliS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:46 INFO - Running setup.py (path:/tmp/pip-ZcCIpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:46 INFO - Running setup.py (path:/tmp/pip-fW11rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:46 INFO - Running setup.py (path:/tmp/pip-mgohnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:46 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)) 13:31:46 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)) 13:31:46 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)) 13:31:46 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)) 13:31:46 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)) 13:31:46 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)) 13:31:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31:46 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 13:31:46 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 13:31:46 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 13:31:46 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 13:31:46 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 13:31:46 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 13:31:48 INFO - Downloading blessings-1.6.tar.gz 13:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:31:48 INFO - Installing collected packages: blessings 13:31:48 INFO - Running setup.py install for blessings 13:31:48 INFO - Successfully installed blessings 13:31:48 INFO - Cleaning up... 13:31:48 INFO - Return code: 0 13:31:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:31:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:48 INFO - Reading from file tmpfile_stdout 13:31:48 INFO - Current package versions: 13:31:48 INFO - argparse == 1.2.1 13:31:48 INFO - blessings == 1.6 13:31:48 INFO - blobuploader == 1.2.4 13:31:48 INFO - docopt == 0.6.1 13:31:48 INFO - functools32 == 3.2.3-2 13:31:48 INFO - jsonschema == 2.5.1 13:31:48 INFO - manifestparser == 1.1 13:31:48 INFO - mozInstall == 1.12 13:31:48 INFO - mozcrash == 0.17 13:31:48 INFO - mozdebug == 0.1 13:31:48 INFO - mozdevice == 0.48 13:31:48 INFO - mozfile == 1.2 13:31:48 INFO - mozhttpd == 0.7 13:31:48 INFO - mozinfo == 0.9 13:31:48 INFO - mozleak == 0.1 13:31:48 INFO - mozlog == 3.3 13:31:48 INFO - moznetwork == 0.27 13:31:48 INFO - mozprocess == 0.23 13:31:48 INFO - mozprofile == 0.28 13:31:48 INFO - mozrunner == 6.12 13:31:48 INFO - mozscreenshot == 0.1 13:31:48 INFO - mozsystemmonitor == 0.3 13:31:48 INFO - moztest == 0.7 13:31:48 INFO - mozversion == 1.4 13:31:48 INFO - psutil == 3.1.1 13:31:48 INFO - requests == 1.2.3 13:31:48 INFO - wsgiref == 0.1.2 13:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:48 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')]} 13:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:48 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')]} 13:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:48 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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:48 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 13:31:48 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 13:31:48 INFO - Using env: {'DISPLAY': ':0', 13:31:48 INFO - 'HOME': '/home/cltbld', 13:31:48 INFO - 'LANG': 'en_US.UTF-8', 13:31:48 INFO - 'LANGUAGE': 'en_US:en', 13:31:48 INFO - 'LOGNAME': 'cltbld', 13:31:48 INFO - 'MAIL': '/var/mail/cltbld', 13:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:48 INFO - 'MOZ_NO_REMOTE': '1', 13:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:48 INFO - 'NO_EM_RESTART': '1', 13:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:48 INFO - 'PWD': '/builds/slave/test', 13:31:48 INFO - 'SHELL': '/bin/bash', 13:31:48 INFO - 'SHLVL': '1', 13:31:48 INFO - 'TERM': 'linux', 13:31:48 INFO - 'TMOUT': '86400', 13:31:48 INFO - 'USER': 'cltbld', 13:31:48 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:48 INFO - '_': '/tools/buildbot/bin/python'} 13:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:31:49 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')]} 13:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 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')]} 13:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 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 0xd8e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', '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 13:31:49 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 13:31:49 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 13:31:49 INFO - Using env: {'DISPLAY': ':0', 13:31:49 INFO - 'HOME': '/home/cltbld', 13:31:49 INFO - 'LANG': 'en_US.UTF-8', 13:31:49 INFO - 'LANGUAGE': 'en_US:en', 13:31:49 INFO - 'LOGNAME': 'cltbld', 13:31:49 INFO - 'MAIL': '/var/mail/cltbld', 13:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:49 INFO - 'MOZ_NO_REMOTE': '1', 13:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:49 INFO - 'NO_EM_RESTART': '1', 13:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:49 INFO - 'PWD': '/builds/slave/test', 13:31:49 INFO - 'SHELL': '/bin/bash', 13:31:49 INFO - 'SHLVL': '1', 13:31:49 INFO - 'TERM': 'linux', 13:31:49 INFO - 'TMOUT': '86400', 13:31:49 INFO - 'USER': 'cltbld', 13:31:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:49 INFO - '_': '/tools/buildbot/bin/python'} 13:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:31:49 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Running post-action listener: _resource_record_post_action 13:31:49 INFO - Running post-action listener: _start_resource_monitoring 13:31:49 INFO - Starting resource monitoring. 13:31:49 INFO - [mozharness: 2016-07-19 20:31:49.419824Z] Finished create-virtualenv step (success) 13:31:49 INFO - [mozharness: 2016-07-19 20:31:49.420258Z] Running install step. 13:31:49 INFO - Running pre-action listener: _resource_record_pre_action 13:31:49 INFO - Running main action method: install 13:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:49 INFO - Reading from file tmpfile_stdout 13:31:49 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:49 INFO - Reading from file tmpfile_stdout 13:31:49 INFO - Output received: 13:31:49 INFO - Usage: mozinstall [options] installer 13:31:49 INFO - Options: 13:31:49 INFO - -h, --help show this help message and exit 13:31:49 INFO - -d DEST, --destination=DEST 13:31:49 INFO - Directory to install application into. [default: 13:31:49 INFO - "/builds/slave/test"] 13:31:49 INFO - --app=APP Application being installed. [default: firefox] 13:31:49 INFO - mkdir: /builds/slave/test/build/application 13:31:49 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'] 13:31:49 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 13:32:06 INFO - Reading from file tmpfile_stdout 13:32:06 INFO - Output received: 13:32:06 INFO - /builds/slave/test/build/application/firefox/firefox 13:32:06 INFO - Running post-action listener: _resource_record_post_action 13:32:06 INFO - [mozharness: 2016-07-19 20:32:06.072126Z] Finished install step (success) 13:32:06 INFO - [mozharness: 2016-07-19 20:32:06.072404Z] Running run-tests step. 13:32:06 INFO - Running pre-action listener: _resource_record_pre_action 13:32:06 INFO - Running main action method: run_tests 13:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:32:06 INFO - Python 2.7.3 13:32:06 INFO - Return code: 0 13:32:06 INFO - grabbing minidump binary from tooltool 13:32:06 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')]} 13:32:06 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 0x104d3b0>, '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 0x104e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:32:06 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 13:32:06 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 13:32:06 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 13:32:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:32:06 INFO - Return code: 0 13:32:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:32:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:32:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:32:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:32:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 13:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 13:32:06 INFO - Using env: {'DISPLAY': ':0', 13:32:06 INFO - 'HOME': '/home/cltbld', 13:32:06 INFO - 'LANG': 'en_US.UTF-8', 13:32:06 INFO - 'LANGUAGE': 'en_US:en', 13:32:06 INFO - 'LOGNAME': 'cltbld', 13:32:06 INFO - 'MAIL': '/var/mail/cltbld', 13:32:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:32:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:06 INFO - 'MOZ_NO_REMOTE': '1', 13:32:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:32:06 INFO - 'NO_EM_RESTART': '1', 13:32:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:06 INFO - 'PWD': '/builds/slave/test', 13:32:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:32:06 INFO - 'SHELL': '/bin/bash', 13:32:06 INFO - 'SHLVL': '1', 13:32:06 INFO - 'TERM': 'linux', 13:32:06 INFO - 'TMOUT': '86400', 13:32:06 INFO - 'USER': 'cltbld', 13:32:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623', 13:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:06 INFO - '_': '/tools/buildbot/bin/python'} 13:32:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 13:32:06 INFO - mozversion application_buildid: 20160719121221 13:32:06 INFO - mozversion application_changeset: 2c04758cf3544a406711b01df129b04dd5376cf3 13:32:06 INFO - mozversion application_display_name: Nightly 13:32:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:32:06 INFO - mozversion application_name: Firefox 13:32:06 INFO - mozversion application_remotingname: firefox 13:32:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:32:06 INFO - mozversion application_vendor: Mozilla 13:32:06 INFO - mozversion application_version: 50.0a1 13:32:06 INFO - mozversion platform_buildid: 20160719121221 13:32:06 INFO - mozversion platform_changeset: 2c04758cf3544a406711b01df129b04dd5376cf3 13:32:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:32:06 INFO - mozversion platform_version: 50.0a1 13:32:06 INFO - using testdate: 1468960326 13:32:06 INFO - actual date: 1468960326 13:32:06 INFO - starting webserver on 'localhost:58884' 13:32:06 INFO - SUITE-START | Running 2 tests 13:32:06 INFO - TEST-START | dromaeo_css 13:32:06 INFO - operating with platform_type : linux_ 13:32:06 INFO - Initialising browser for dromaeo_css test... 13:32:06 INFO - TEST-INFO | started process 1073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUmJVKs/profile http://localhost:58884/getInfo.html) 13:32:10 INFO - PROCESS | 1073 | __metrics Screen width/height:1600/1200 13:32:10 INFO - PROCESS | 1073 | colorDepth:24 13:32:10 INFO - PROCESS | 1073 | Browser inner width/height: 1024/697 13:32:10 INFO - PROCESS | 1073 | __metrics 13:32:11 INFO - PROCESS | 1073 | 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" 13:32:11 INFO - PROCESS | 1073 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:32:12 INFO - TEST-INFO | 1073: exit 0 13:32:12 INFO - Browser initialized. 13:32:12 INFO - Running cycle 1/1 for dromaeo_css test... 13:32:12 INFO - TEST-INFO | started process 1163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUmJVKs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:24 INFO - PROCESS | 1163 | RSS: Main: 158908416 13:32:24 INFO - PROCESS | 1163 | 13:33:15 INFO - PROCESS | 1163 | 1468960395368 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] 13:35:28 INFO - PROCESS | 1163 | Cycle 1(1): loaded http://localhost:58884/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58884/tests/dromaeo/cssquery-ext.html) 13:35:29 INFO - PROCESS | 1163 | RSS: Main: 242085888 13:35:29 INFO - PROCESS | 1163 | 13:38:32 INFO - PROCESS | 1163 | Cycle 1(1): loaded http://localhost:58884/tests/dromaeo/cssquery-ext.html (next: http://localhost:58884/tests/dromaeo/cssquery-jquery.html) 13:38:32 INFO - PROCESS | 1163 | RSS: Main: 250634240 13:38:32 INFO - PROCESS | 1163 | 13:41:35 INFO - PROCESS | 1163 | Cycle 1(1): loaded http://localhost:58884/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58884/tests/dromaeo/cssquery-mootools.html) 13:41:35 INFO - PROCESS | 1163 | RSS: Main: 249847808 13:41:35 INFO - PROCESS | 1163 | 13:44:37 INFO - PROCESS | 1163 | Cycle 1(1): loaded http://localhost:58884/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58884/tests/dromaeo/cssquery-prototype.html) 13:44:38 INFO - PROCESS | 1163 | RSS: Main: 241053696 13:44:38 INFO - PROCESS | 1163 | 13:47:41 INFO - PROCESS | 1163 | Cycle 1(1): loaded http://localhost:58884/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58884/tests/dromaeo/cssquery-yui.html) 13:47:42 INFO - PROCESS | 1163 | RSS: Main: 233664512 13:47:42 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | Cycle 1(1): loaded http://localhost:58884/tests/dromaeo/cssquery-yui.html (next: http://localhost:58884/tests/dromaeo/cssquery-dojo.html) 13:50:45 INFO - PROCESS | 1163 | RSS: Main: 278872064 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | __start_tp_report 13:50:45 INFO - PROCESS | 1163 | _x_x_mozilla_page_load 13:50:45 INFO - PROCESS | 1163 | _x_x_mozilla_page_load_details 13:50:45 INFO - PROCESS | 1163 | |i|pagename|runs| 13:50:45 INFO - PROCESS | 1163 | |0;dojo.html;2590.689117305923;2596.4677241165564;2622.462395208982;2632.828866123702;2634.6179803928417;4668.719876807394;4738.123447162273;4740.4548476925165;4762.119007983521;4782.378290822188;4731.030138821542;4751.239801631739;4759.547842954922;4793.137235297654;4793.640476964224;4331.696781225308;4340.348947657861;4430.291153415455;4458.175237581042;4516.683832131752;4442.111577684476;4463.129689710508;4489.5285994970545;4498.910021799562;4533.138703646313;4312.310030395121;4328.696991210617;4364.323529852878;4374.147041326911;4390.517043125254;4282.036541778106;4291.35629655551;4304.3113101903855;4327.0913108247305;4343.457067866517;4314.633256173229;4317.41714868495;4318.589733975267;4354.303311470149;4374.0595771908875;4622.953770462286;4703.976480117612;4722.76386180693;4760.619150467955;4770.212914869043;5121.667091639031;5158.4067832199025;5174.715390653512;5180.119379705459;5184.481551844823;5163.251328557376;5173.120569503193;5201.40183878855;5212.661177023481;5215.556677682402;4617.399738034068;4620.099080679235;4645.767711614406;4680.53194680536;4760.166970780099;5143.331366922351;5157.613179011589;5178.378594568676;5188.325517682677;5214.97392513035;4933.062718083553;4947;4978.452370239271;4998.05037042968;5001.274815151855;4938.456769755324;5062.291279220912;5081;5096.668716533413;5155.561777248976;5056.039352523009;5064.290999260106;5065.7720402581235;5090.10923088458;5146.588272938156;5049.444561098277;5062.924056139161;5072.315237442972;5081.5426611605135;5086.745662716849;4149.398337241083;4200.264953633102;4203.558626344251;4250.766207858597;4250.957490425118;3718.163413232055;3747.437884317374;3773.396256598931;3775;3775.8489660413275;4360.324149756792;4372.781360931941;4380.036391993756;4382.890427739332;4393.538678438782;5111.412187598399;5112.258722485293;5115.335006449138;5124.077666020077;5159.097157997336;4402.955970440319;4403.691741578059;4404.647628189737;4438.822447102144;4456.398386217884;4455.821767129279;4516.36770852081;4547.522510136454;4556.794944227518;4571.291449825216;4834.903301933871;4837.540433658867;4842.5157484251995;4878.634102442331;4884.462709102067;5058.418281897481;5071;5097.464766199644;5116.565091967075;5117.053345131163;4708.482066972561;4749.691270067435;4751.144793937125;4754.976225118852;4873.658843880894;4983.152864013199;4983.775730092227;4986.476419975996;4994.550490455877;5054.21659642756;5003.54968052877;5029.572486338702;5035.219540971156;5041.621878359211;5088.516590923857;4791.065742180242;4878.463369029333;4930.778114984906;4942.258661200849;4950.653454258168;4953.653244272867;4988.251762235694;4993.850184494471;4995;5045.949540504548;4698.647601429838;4894.216925291937;4902.632302577393;4905.656604037826;4960.429550601582;4980.427250866197;5034.974825125997;5043.193089105725;5052.343195384577;5071.4421413645205;4947.233178857283;4964.429090654477;4967.05625931072;4967.230079337708;5012.423571289202 13:50:45 INFO - PROCESS | 1163 | |1;ext.html;585.209966545164;599.0505049496546;606.1392822192486;612.9793893167875;622.5268795715255;40166.598334016686;42328.36507452387;42353.36469952949;42399.36400953988;46243.53756462434;43432.13135737283;43879.3418098728;43937.34093988593;43987.78006109973;44020;7540.2082781308;7935;8055.6777728890775;8067.193280671944;10220.182385409149;10089.798204035915;10474.528646210902;10477.947610261976;10519.316244444086;10525.157987361032;10569;10616.78766424671;10619.25665203436;10622.893771062307;10628.415437150954;7599.620018999056;7748.845023099563;7757.263060009346;10190.541425635864;10482.266241363108;10005.799884002352;10378.169746420268;10379.169666426744;10391.48042597867;10391.636292729718;40127;40513.79743101266;40633.79683101566;40647.59352406497;40735.388969165484;2983.298924752681;3124.5313203019728;3157.589513363248;3333.21669407689;3448.758586898918;30611.09194626507;30678.539821902465;30771;30901;31175.84412077925;3182.6658200888805;3211.020638705194;3219.0664707234873;3236.0453666168537;3257.9185520361943;190905;190910;191739;276311;276422;32866.342673146406;33745.49381759275;55657.44342556604;56194.15708764372;56300;35190.29619407649;35249.82375088108;35365.64634353675;35367.292654146775;35414.29171416609;13292.20246785196;13477;13497.190168589916;13499.055066145473;13504.324783760772;10429;11164.832527512093;11561.76876462466;11612.88387116118;11822.34977076269;8064.596770161468;8066.274035336848;8085.272325490734;8143.226393492609;8163.408135413656;3125.4530457169903;3152.9122452753763;3175;3189.059227527885;3191.7446604271604;929.6606738540519;959.7648576098901;985.6008316631633;987.3483500889379;1007.1439276614818;29491.85254073716;29575.26061848471;29748.702512975025;29903.551446728317;30195.69804301973;981.8085473332775;1014.4775440648073;1015.7663737340453;1043.0456132638703;1097.2429023973432;15200.163990980382;15473.381064757508;15655.84344156544;15827.208639567976;15954.521364359089;785494;797859;803493;804084;804706;49142.26286605704;49665.50334496681;49710.75144624326;49865.25202121971;50096.74951625219;1101.5593762495066;1161.802493576111;1179;1205.5177928828364;1210.4069006186905;28377.858110709316;29545.704542954296;30006;30522.694773052874;30568.235794105327;43269.350959735;48161.5183848157;50020.24969625531;50020.49979500158;55505.722471389;354544;357399;358515;360485;361008;32578;32878.34243315073;35104.82447587746;40236.39645405263;43100.13799723925;43431.565684344016;43739;44020.33969490396;44201.33697994468;44799;37339.813300933325;37489.81255093707;38323.616763832004;38450.807745961094;38582.2283554333;35805.82097089498;38222.80888595539;39222.2155556893;39497.80251098726;39758.2048359037;36629;37393.2521349566;37568.81215593904;37621;38037.80981095077;30096.742744293828;32174.83912580422;34507;35088.82455587706;35118.29763404769;25568.616470752582;25644.358891027874;25841;25891.19509724221;25950.740492594832 13:50:45 INFO - PROCESS | 1163 | |2;jquery.html;340.88382777956184;363.19552191894945;370.9387950988087;373.7794701126335;384.3312636013338;13071.346432678363;13749;15036.924815375907;17547;17552;16143.112128832909;17234.913825430856;17641.82358176418;17647.02941338229;17661.20524576394;5085.872853178673;5208.062548741225;5239.13554263546;5245.659032162897;5246.606504512158;3846.499955005845;5871.295444546639;6158.84602884927;6163.537734669895;6270.526426289818;6032.426919442668;6068.908966365508;6192.040233763774;6217.285012223584;6234.283057448383;6140.969295153518;6173.69131543421;6176.505879529627;6225.719842607094;6234.781782637631;5206.140986737184;5359.08895487768;5359.598030147716;5387.1380579107545;5445.53712934401;21501.67747483789;21713.457163570878;21889.452763680874;22084.88957555202;22126.22558210471;1895.1282410091374;2021.3632705697805;2071.9585608287903;2284.2119468783244;2333.6499525071204;12790.936045319806;13499.595012149652;13508.121972071882;13917.721645567133;13976.720465590632;1713.5705487482173;1728.4209789720333;1762.7972783129976;1812.8640351973522;1827.41522712732;70637.64681176613;73235.07059717605;73796;74760.99999999946;75005;9372.8594071089;9443.952780236192;9704.563294651616;9844.3601165924;10060.295779295526;13178.406971686298;13292.069555131242;13317.33413329349;13325.866741332657;13341.19952802835;7349.30181632744;7401;7415.295546923034;7535.058117735283;7568.205338439385;7152.821179470555;7242.420606351585;7311.451641126937;7419.591922444318;7442.218567050381;6040.848978775478;6186.319504854462;6243.469305109116;6248;6265.9402383717;1747.9475615731549;1752.2202619834306;1795.7216631421888;1819.3814103204975;1849.556106534422;846.3652260804397;882.43965082172;886.8093359927647;924.6209054287709;961.6489981156827;18139.90930045341;18382.356617518322;18421;18686.813131868777;18723.438296851116;879.331707901999;895.5656506594297;904.9366544341833;932.9860052099224;943.8537026760863;9889.554970026365;10315.948420257851;10324.22568307365;10477.580896764044;10480;373177;375535;379521;380938;380956;44312.73242076513;63396.68301658462;63528.68235658885;70076.64961675159;71558.28441715516;811.4725428471422;819.3158712475031;835.3150416658282;835.5279267214005;839.693511868176;16439.09584972838;16621.83378166203;16727.665446690753;16905.239264233383;16978.745318820474;34448.48327275143;35930.28139437144;35953.460698089024;36284.45573316349;36704.63295367012;44128.33807492826;47693;47775.76112119556;48137;49058.50941490539;4554.954450455453;5985.491233245223;7548.698052077855;7565.130010048915;8063.395245356739;12390.132690711564;12465.189762665397;12556.811647825107;12587.370631468573;12878.935605321913;10711.678649640524;10796.67609971702;11025.393603351893;11027.338359698444;11163.832542511705;10463;10677.786444271227;10690.946545267223;10694;11455.198136130724;9972.700818975152;10294.073533382032;10315.535800889129;10354.378737275787;10533.78932421332;7341.151130788586;7497.212792656911;7626;7687.923120768721;7764.534127952341;9999.60001599957;10095.697129085846;10614.416207108374;11034.889651103707;11135.220534562503 13:50:45 INFO - PROCESS | 1163 | |3;mootools.html;1387.4658256571217;1436.2387934394771;1502.8497150284966;1713.0321368426846;1731.5584525945874;7568.356689681377;7948.682052717884;8025.719099831507;8033.598320083995;8036.236557527026;6771.661416929146;7523.285287897655;7774.455788094836;7786.688532458695;7919.524828510293;2191.3097374327117;2215.4129155773735;2221.5445833604153;2227.10915633747;2236.1837929155904;2221.1115553778454;2231.5760005598963;2262.6606009098687;2264.7169103862016;2265.9433514162197;1881.6801143805517;1948.3667807962397;1954.7165660979222;1960.7647082350138;2121.5332626822074;2168.56628674265;2195.297504798465;2200.7579166291775;2205.7794220577975;2216.3240211735415;2176.5102851858364;2184.322859913432;2191.3535507025467;2250.076184861936;2254.8985295661732;7870.921290787076;7980.122186559473;7981.201879812031;7985.081715602722;7985.840283194304;4641.814327426899;4806.663533552653;4810.206315957863;4810.8316208932865;4811.566958973674;2807.9438411231863;2873.281679580105;2907.185987923385;2921.5033444314304;2952.5866378706996;832.6045128563884;855.4867079752099;861.6467248428177;862.3790870573177;867.7677697669325;10526;10581.31221470602;10584.153267738639;10608.15134789215;10609.84085238722;3947.210557888434;4345;4352.107817897323;4364.039911219526;4365.214261432909;9090.545472726337;9101.81796364069;9135.177833994972;9152.542372881462;9159.725208243763;4739.526047395302;4745.477997420281;4747.145513807548;4749.525047495292;4763.666543341933;4269.380939763779;4320.611144996966;4334.739915605074;4372.300431930876;4392.121575684876;3658.286634106377;3683.5211422515436;3737.439384092408;3780.7353485255767;3792;1208.546794951893;1209.6854817747274;1214.1379620469388;1230.56930074475;1236.6722818453118;706.2231545300128;796.6335485676537;798.7364169823944;804.7143264141263;878.6529320918202;5213.817516386908;5372.3016007919505;5420.403755586883;5437.592180586472;5447.782088716407;864.5072308783933;898.8876390451194;905.090384163911;909.3270979475141;913.8857642794651;3474.826258687106;3639.6178401267484;3651.817409129586;3686.0600546860915;3719.795411252353;9417;9693.51532423376;9825.754356141153;9834.557444914995;9848.60605575769;9713.271504637181;9720.368176068674;9736.269779766546;9747.805043899083;9761.121499065119;7415.517991330654;7510.211427800112;7548.320651141424;7556.546607203585;7564.054493188351;5193.974030129825;5390.164524498709;5503.394626591152;5539;5666.744996475251;8604.354673399395;8943.284537237136;8945.821083578423;9051.773705657148;9146.31402644791;9023.684171053981;9135.269178465574;9209.447433154031;9243.676471323472;9259.953700231456;7237.927620723725;7389.482736208413;7448.143463501767;7513.436492262993;7559.470837041355;7664.233576642291;7665.156832748505;7717.961410192913;7725.343345815444;7726.575038372944;6572.737090516326;6583.539152259296;6695.263521012782;6698.430633396216;6790.796276111724;6611.537192396486;6669.56560855294;6693.564918280296;6719;6773;6538.8365290868105;6601.834954126185;6624.668766561749;6629.07192992991;6630.9336906630315;4278.914421711611;4454.510003899503;4588.862334129982;4628.814847406066;4630.143423466669;4516.706414083074;4599.586037256529;4605.700629459075;4607.769611519344;4669.18289299378 13:50:45 INFO - PROCESS | 1163 | |4;prototype.html;276.78963987369593;293.1001824399093;296.36873459531193;305.1166871905833;309.1267170244061;4582.4730156032065;4856.708597484149;4943.802247910079;4981.252812078181;4985.326980857583;4762;4889.3888263967;4907.975460122703;4908.901821963558;4926.852194434173;3639.2175682228317;3790.431435284702;3854.479645247891;3874.372763439362;3904.824282907262;3749.362608356586;3851.268259030789;3867.7292589518743;3880.2433525462548;3893.922121557567;3823.5220597425323;3857.1707082977155;3864.574896761354;3864.9757814179266;3870.748401353903;3862.9227415451674;3864.3044252034483;3868.574456809749;3877.166409222031;3883.456316115746;3187.474066778979;3325.334933013407;3329.583802024747;3365.4615261558033;3456.2914602506676;4728.95271047292;4743.596794272491;4747.548982846659;4761.071765934802;4769.785359658824;4157.91684166315;4305.461817272841;4309.052008558112;4352.717073390219;4361.476622805252;5111.488851114896;5117.616178786606;5132;5143.254228136899;5188.143956247215;3642.963570364289;3659.1949771050326;3662.4434615288396;3664.8534058637624;3666.4866918631415;5247.475252474722;5315.302856691293;5395.163749618815;5423.728813559345;5425.891482170335;3699.204670995749;3705.6479634434686;3715.275521273326;3720.2373513429684;3722.8696995605023;3746.9437958430644;3774.490443790053;3781.111438811877;3783.5838057813617;3788.0908581940134;4332.176886391576;4338.891527711832;4345.891352716144;4348.869533913988;4351.586599273064;5053.166227572409;5091.592672586185;5108;5125.666831655931;5190.143626301618;1287.028293638297;1291.3156027305542;1301.6485548901744;1304.0024381348276;1305.7388522295578;805.9120187746495;837.3552364679284;840.8864803251482;843.1442086282428;846.8137009857821;4125.143685341548;4159.729617574848;4337.240982827992;4473.932891006638;4502.4371953505815;5316.069687804618;5321.866953326198;5331.413544510101;5332.68003919766;5341.225522299244;4557.954420455753;4561.657875659339;4562.224421848294;4578.427696537933;4590.862274131781;2723.891044358243;2740.3012231880743;2796.398774263543;2797.790165737578;2807.719228077176;4961.652684312064;4967.130752118364;4975.303457515951;4981.875453113628;5019.498050194951;5367.329083864517;5387.17173483924;5388.407275199804;5396.514313711785;5411.134218525017;4866.367372241586;4954.653174277766;4957.776900039579;4964.503549645006;4973.975130124326;5238.135707608202;5245.66235609917;5247.317848679647;5262.789488420573;5332.173513105475;4951.727654978867;5190.740462976912;5207.901298727251;5219.765110569958;5227.843164705065;4943.406791185081;4951.876203094951;4986.252062190701;5017.598592112695;5021.271915572294;3687.2256826065754;3716.461113138526;3728.800007995296;3740.8877733668032;3763.962360376361;5201.323827902348;5316.149416093406;5328.653637513549;5364.946350536445;5369.677819330853;5091;5118.590512758896;5119.872003199801;5121.283020377225;5132.9743351283005;5055.69665820052;5069.645124841226;5097.286379906742;5107.719075450737;5113.4375218726655;4752.524747525358;5001.8499445016705;5017.247412888096;5026.421961474477;5027.416363845377;4897.755112244445;5061.519155680205;5129.281480815174;5152.407473140637;5153.45888681683;4938.728369939688;4959.231319145538;4974.17926042199;4978.182963218472;5001.1248031593595 13:50:45 INFO - PROCESS | 1163 | |5;yui.html;620.1906512001838;651.5504302031595;652.0512654088302;658.4469046001358;701.7929710735333;12045.819312710306;13080.73838523229;13544.05191636586;13643.044986850911;13664.863351366495;12972;13194.340282985837;13195.934020329885;13211.14127581709;13213.942884569211;614.1524695919625;767.2710924621632;775.2518819339336;794.8291117409756;797.4258533855643;788.1291173253529;788.8106854354942;791.635847510146;791.7189397763798;791.7505985614513;834.2575108153748;836.3977935886152;836.477201748907;836.5357226739158;838.0153319849184;642.8425035866219;796.1958421993799;816.3795515408273;819.2626636027563;821.6836517940576;604.4590091867798;604.9001914684072;606.4420732925719;609.8261995331309;609.9359567245464;13078.346082695922;13114.672133196651;13126.343682815821;13133.60599182026;13138.080334376598;11085.612003579916;11259.43702814864;11446.713832154179;11618.244814087057;11641.70895727605;10118.949405252926;10322;10348.637797677045;10395.428251446167;10607.628732994308;4020.6783457323345;4037.1320166164414;4037.8788636340955;4059.451973983504;4119.814608342632;62926.37073629251;63014;63987;65235;65501.67249163771;9192.908070919206;9246.26029917605;9284.72145835626;9629;9653.420794752337;17455.56361090983;17477.475675729747;17485.03832289211;17522.91238543799;17533.123343832758;6598.406143447017;6618.63597502142;6696.933030669728;6754.729810807513;6868.725250989904;3668.211334563082;3703.8703645372284;3720.311742327678;3720.627937206312;3779.6976241900584;2647.1661426650544;2734.849583272939;2735.7811375089946;2738.7398197171237;2740.8355498670144;393.73029474809846;467.752091391563;493.13454886673657;494.868859752166;532.579262382721;542.866997585594;560.6944215402615;579.8550362409397;593.3176846626415;597.5232660621699;7484.550926944401;7748.961255193801;7828.960855195802;8285;8657.480551166951;587.7766448749447;588.6350462713145;589.4400319696219;602.9005214139618;611.5596770325359;3797.7721336719887;3947.913082075268;4038.8586399476467;4042.535108462506;4055.858044968471;105045;106959;107793;109008;110565.44717276363;89788.5510572443;91176;91577;94481.5275923616;95341.52329238449;45751.856203594514;48414;48646.513534864906;49453.25820112701;49543;8256.83486330282;8391.118932511998;8480.448770829877;8517.659293628185;8519.020312664123;41894.37158442565;42780;43678.11519470188;43684.12631747411;43969.12061758811;36764.63235367612;56537.71731141318;64303.03545446729;65808;66992.66503667645;11481.770364592829;11608.941955290169;11792.52829886795;11804.645860624196;12253.509859605516;8942.50816205115;12912.548060817826;12979.480820767441;13585.184888906697;13673.726525469236;7561.4706970513735;11095;11396.145289103517;11722.472488738198;12061.879381206427;11491.425428728697;11519.76960460803;11660.941695291469;11980.101492387932;12129.69675758113;11220.1023918088;11733;11763.176577639482;11782.057435405319;11984.700382490159;9906.455144967096;10064.44645544502;10348.482575871327;10390.376577405379;10452.529636166239;9454.480003599869;9620.13418792312;10114.195737155978;10138.267223590115;10158.695239142837 13:50:45 INFO - PROCESS | 1163 | __end_tp_report 13:50:45 INFO - PROCESS | 1163 | __start_cc_report 13:50:45 INFO - PROCESS | 1163 | _x_x_mozilla_cycle_collect,912 13:50:45 INFO - PROCESS | 1163 | __end_cc_report 13:50:45 INFO - PROCESS | 1163 | __startTimestamp1468961445358__endTimestamp 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | ------- Summary: start ------- 13:50:45 INFO - PROCESS | 1163 | Number of tests: 6 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | [#0] dojo.html Cycles:165 Average:4699.39 Median:4837.54 stddev:504.88 (10.4%) stddev-sans-first:478.56 13:50:45 INFO - PROCESS | 1163 | Values: 2590.7 2596.5 2622.5 2632.8 2634.6 4668.7 4738.1 4740.5 4762.1 4782.4 4731.0 4751.2 4759.5 4793.1 4793.6 4331.7 4340.3 4430.3 4458.2 4516.7 4442.1 4463.1 4489.5 4498.9 4533.1 4312.3 4328.7 4364.3 4374.1 4390.5 4282.0 4291.4 4304.3 4327.1 4343.5 4314.6 4317.4 4318.6 4354.3 4374.1 4623.0 4704.0 4722.8 4760.6 4770.2 5121.7 5158.4 5174.7 5180.1 5184.5 5163.3 5173.1 5201.4 5212.7 5215.6 4617.4 4620.1 4645.8 4680.5 4760.2 5143.3 5157.6 5178.4 5188.3 5215.0 4933.1 4947.0 4978.5 4998.1 5001.3 4938.5 5062.3 5081.0 5096.7 5155.6 5056.0 5064.3 5065.8 5090.1 5146.6 5049.4 5062.9 5072.3 5081.5 5086.7 4149.4 4200.3 4203.6 4250.8 4251.0 3718.2 3747.4 3773.4 3775.0 3775.8 4360.3 4372.8 4380.0 4382.9 4393.5 5111.4 5112.3 5115.3 5124.1 5159.1 4403.0 4403.7 4404.6 4438.8 4456.4 4455.8 4516.4 4547.5 4556.8 4571.3 4834.9 4837.5 4842.5 4878.6 4884.5 5058.4 5071.0 5097.5 5116.6 5117.1 4708.5 4749.7 4751.1 4755.0 4873.7 4983.2 4983.8 4986.5 4994.6 5054.2 5003.5 5029.6 5035.2 5041.6 5088.5 4791.1 4878.5 4930.8 4942.3 4950.7 4953.7 4988.3 4993.9 4995.0 5045.9 4698.6 4894.2 4902.6 4905.7 4960.4 4980.4 5035.0 5043.2 5052.3 5071.4 4947.2 4964.4 4967.1 4967.2 5012.4 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | [#1] ext.html Cycles:180 Average:59609.72 Median:35096.82 stddev:141702.28 (403.7%) stddev-sans-first:142030.49 13:50:45 INFO - PROCESS | 1163 | Values: 585.2 599.1 606.1 613.0 622.5 40166.6 42328.4 42353.4 42399.4 46243.5 43432.1 43879.3 43937.3 43987.8 44020.0 7540.2 7935.0 8055.7 8067.2 10220.2 10089.8 10474.5 10477.9 10519.3 10525.2 10569.0 10616.8 10619.3 10622.9 10628.4 7599.6 7748.8 7757.3 10190.5 10482.3 10005.8 10378.2 10379.2 10391.5 10391.6 40127.0 40513.8 40633.8 40647.6 40735.4 2983.3 3124.5 3157.6 3333.2 3448.8 30611.1 30678.5 30771.0 30901.0 31175.8 3182.7 3211.0 3219.1 3236.0 3257.9 190905.0 190910.0 191739.0 276311.0 276422.0 32866.3 33745.5 55657.4 56194.2 56300.0 35190.3 35249.8 35365.6 35367.3 35414.3 13292.2 13477.0 13497.2 13499.1 13504.3 10429.0 11164.8 11561.8 11612.9 11822.3 8064.6 8066.3 8085.3 8143.2 8163.4 3125.5 3152.9 3175.0 3189.1 3191.7 929.7 959.8 985.6 987.3 1007.1 29491.9 29575.3 29748.7 29903.6 30195.7 981.8 1014.5 1015.8 1043.0 1097.2 15200.2 15473.4 15655.8 15827.2 15954.5 785494.0 797859.0 803493.0 804084.0 804706.0 49142.3 49665.5 49710.8 49865.3 50096.7 1101.6 1161.8 1179.0 1205.5 1210.4 28377.9 29545.7 30006.0 30522.7 30568.2 43269.4 48161.5 50020.2 50020.5 55505.7 354544.0 357399.0 358515.0 360485.0 361008.0 32578.0 32878.3 35104.8 40236.4 43100.1 43431.6 43739.0 44020.3 44201.3 44799.0 37339.8 37489.8 38323.6 38450.8 38582.2 35805.8 38222.8 39222.2 39497.8 39758.2 36629.0 37393.3 37568.8 37621.0 38037.8 30096.7 32174.8 34507.0 35088.8 35118.3 25568.6 25644.4 25841.0 25891.2 25950.7 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | [#2] jquery.html Cycles:180 Average:23932.59 Median:380947.00 stddev:62194.48 (16.3%) stddev-sans-first:62343.72 13:50:45 INFO - PROCESS | 1163 | Values: 340.9 363.2 370.9 373.8 384.3 13071.3 13749.0 15036.9 17547.0 17552.0 16143.1 17234.9 17641.8 17647.0 17661.2 5085.9 5208.1 5239.1 5245.7 5246.6 3846.5 5871.3 6158.8 6163.5 6270.5 6032.4 6068.9 6192.0 6217.3 6234.3 6141.0 6173.7 6176.5 6225.7 6234.8 5206.1 5359.1 5359.6 5387.1 5445.5 21501.7 21713.5 21889.5 22084.9 22126.2 1895.1 2021.4 2072.0 2284.2 2333.6 12790.9 13499.6 13508.1 13917.7 13976.7 1713.6 1728.4 1762.8 1812.9 1827.4 70637.6 73235.1 73796.0 74761.0 75005.0 9372.9 9444.0 9704.6 9844.4 10060.3 13178.4 13292.1 13317.3 13325.9 13341.2 7349.3 7401.0 7415.3 7535.1 7568.2 7152.8 7242.4 7311.5 7419.6 7442.2 6040.8 6186.3 6243.5 6248.0 6265.9 1747.9 1752.2 1795.7 1819.4 1849.6 846.4 882.4 886.8 924.6 961.6 18139.9 18382.4 18421.0 18686.8 18723.4 879.3 895.6 904.9 933.0 943.9 9889.6 10315.9 10324.2 10477.6 10480.0 373177.0 375535.0 379521.0 380938.0 380956.0 44312.7 63396.7 63528.7 70076.6 71558.3 811.5 819.3 835.3 835.5 839.7 16439.1 16621.8 16727.7 16905.2 16978.7 34448.5 35930.3 35953.5 36284.5 36704.6 44128.3 47693.0 47775.8 48137.0 49058.5 4555.0 5985.5 7548.7 7565.1 8063.4 12390.1 12465.2 12556.8 12587.4 12878.9 10711.7 10796.7 11025.4 11027.3 11163.8 10463.0 10677.8 10690.9 10694.0 11455.2 9972.7 10294.1 10315.5 10354.4 10533.8 7341.2 7497.2 7626.0 7687.9 7764.5 9999.6 10095.7 10614.4 11034.9 11135.2 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | [#3] mootools.html Cycles:180 Average:5218.30 Median:5442.69 stddev:2913.34 (53.5%) stddev-sans-first:2907.29 13:50:45 INFO - PROCESS | 1163 | Values: 1387.5 1436.2 1502.8 1713.0 1731.6 7568.4 7948.7 8025.7 8033.6 8036.2 6771.7 7523.3 7774.5 7786.7 7919.5 2191.3 2215.4 2221.5 2227.1 2236.2 2221.1 2231.6 2262.7 2264.7 2265.9 1881.7 1948.4 1954.7 1960.8 2121.5 2168.6 2195.3 2200.8 2205.8 2216.3 2176.5 2184.3 2191.4 2250.1 2254.9 7870.9 7980.1 7981.2 7985.1 7985.8 4641.8 4806.7 4810.2 4810.8 4811.6 2807.9 2873.3 2907.2 2921.5 2952.6 832.6 855.5 861.6 862.4 867.8 10526.0 10581.3 10584.2 10608.2 10609.8 3947.2 4345.0 4352.1 4364.0 4365.2 9090.5 9101.8 9135.2 9152.5 9159.7 4739.5 4745.5 4747.1 4749.5 4763.7 4269.4 4320.6 4334.7 4372.3 4392.1 3658.3 3683.5 3737.4 3780.7 3792.0 1208.5 1209.7 1214.1 1230.6 1236.7 706.2 796.6 798.7 804.7 878.7 5213.8 5372.3 5420.4 5437.6 5447.8 864.5 898.9 905.1 909.3 913.9 3474.8 3639.6 3651.8 3686.1 3719.8 9417.0 9693.5 9825.8 9834.6 9848.6 9713.3 9720.4 9736.3 9747.8 9761.1 7415.5 7510.2 7548.3 7556.5 7564.1 5194.0 5390.2 5503.4 5539.0 5666.7 8604.4 8943.3 8945.8 9051.8 9146.3 9023.7 9135.3 9209.4 9243.7 9260.0 7237.9 7389.5 7448.1 7513.4 7559.5 7664.2 7665.2 7718.0 7725.3 7726.6 6572.7 6583.5 6695.3 6698.4 6790.8 6611.5 6669.6 6693.6 6719.0 6773.0 6538.8 6601.8 6624.7 6629.1 6630.9 4278.9 4454.5 4588.9 4628.8 4630.1 4516.7 4599.6 4605.7 4607.8 4669.2 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | [#4] prototype.html Cycles:180 Average:4250.82 Median:4893.57 stddev:1240.06 (25.3%) stddev-sans-first:1207.13 13:50:45 INFO - PROCESS | 1163 | Values: 276.8 293.1 296.4 305.1 309.1 4582.5 4856.7 4943.8 4981.3 4985.3 4762.0 4889.4 4908.0 4908.9 4926.9 3639.2 3790.4 3854.5 3874.4 3904.8 3749.4 3851.3 3867.7 3880.2 3893.9 3823.5 3857.2 3864.6 3865.0 3870.7 3862.9 3864.3 3868.6 3877.2 3883.5 3187.5 3325.3 3329.6 3365.5 3456.3 4729.0 4743.6 4747.5 4761.1 4769.8 4157.9 4305.5 4309.1 4352.7 4361.5 5111.5 5117.6 5132.0 5143.3 5188.1 3643.0 3659.2 3662.4 3664.9 3666.5 5247.5 5315.3 5395.2 5423.7 5425.9 3699.2 3705.6 3715.3 3720.2 3722.9 3746.9 3774.5 3781.1 3783.6 3788.1 4332.2 4338.9 4345.9 4348.9 4351.6 5053.2 5091.6 5108.0 5125.7 5190.1 1287.0 1291.3 1301.6 1304.0 1305.7 805.9 837.4 840.9 843.1 846.8 4125.1 4159.7 4337.2 4473.9 4502.4 5316.1 5321.9 5331.4 5332.7 5341.2 4558.0 4561.7 4562.2 4578.4 4590.9 2723.9 2740.3 2796.4 2797.8 2807.7 4961.7 4967.1 4975.3 4981.9 5019.5 5367.3 5387.2 5388.4 5396.5 5411.1 4866.4 4954.7 4957.8 4964.5 4974.0 5238.1 5245.7 5247.3 5262.8 5332.2 4951.7 5190.7 5207.9 5219.8 5227.8 4943.4 4951.9 4986.3 5017.6 5021.3 3687.2 3716.5 3728.8 3740.9 3764.0 5201.3 5316.1 5328.7 5364.9 5369.7 5091.0 5118.6 5119.9 5121.3 5133.0 5055.7 5069.6 5097.3 5107.7 5113.4 4752.5 5001.8 5017.2 5026.4 5027.4 4897.8 5061.5 5129.3 5152.4 5153.5 4938.7 4959.2 4974.2 4978.2 5001.1 13:50:45 INFO - PROCESS | 1163 | 13:50:45 INFO - PROCESS | 1163 | [#5] yui.html Cycles:180 Average:17347.09 Median:43826.62 stddev:25603.16 (58.4%) stddev-sans-first:25644.18 13:50:45 INFO - PROCESS | 1163 | Values: 620.2 651.6 652.1 658.4 701.8 12045.8 13080.7 13544.1 13643.0 13664.9 12972.0 13194.3 13195.9 13211.1 13213.9 614.2 767.3 775.3 794.8 797.4 788.1 788.8 791.6 791.7 791.8 834.3 836.4 836.5 836.5 838.0 642.8 796.2 816.4 819.3 821.7 604.5 604.9 606.4 609.8 609.9 13078.3 13114.7 13126.3 13133.6 13138.1 11085.6 11259.4 11446.7 11618.2 11641.7 10118.9 10322.0 10348.6 10395.4 10607.6 4020.7 4037.1 4037.9 4059.5 4119.8 62926.4 63014.0 63987.0 65235.0 65501.7 9192.9 9246.3 9284.7 9629.0 9653.4 17455.6 17477.5 17485.0 17522.9 17533.1 6598.4 6618.6 6696.9 6754.7 6868.7 3668.2 3703.9 3720.3 3720.6 3779.7 2647.2 2734.8 2735.8 2738.7 2740.8 393.7 467.8 493.1 494.9 532.6 542.9 560.7 579.9 593.3 597.5 7484.6 7749.0 7829.0 8285.0 8657.5 587.8 588.6 589.4 602.9 611.6 3797.8 3947.9 4038.9 4042.5 4055.9 105045.0 106959.0 107793.0 109008.0 110565.4 89788.6 91176.0 91577.0 94481.5 95341.5 45751.9 48414.0 48646.5 49453.3 49543.0 8256.8 8391.1 8480.4 8517.7 8519.0 41894.4 42780.0 43678.1 43684.1 43969.1 36764.6 56537.7 64303.0 65808.0 66992.7 11481.8 11608.9 11792.5 11804.6 12253.5 8942.5 12912.5 12979.5 13585.2 13673.7 7561.5 11095.0 11396.1 11722.5 12061.9 11491.4 11519.8 11660.9 11980.1 12129.7 11220.1 11733.0 11763.2 11782.1 11984.7 9906.5 10064.4 10348.5 10390.4 10452.5 9454.5 9620.1 10114.2 10138.3 10158.7 13:50:45 INFO - PROCESS | 1163 | -------- Summary: end -------- 13:50:45 INFO - PROCESS | 1163 | 13:50:46 INFO - TEST-INFO | 1163: exit 0 13:50:46 INFO - TEST-OK | dromaeo_css | took 1119721ms 13:50:46 INFO - TEST-START | kraken 13:50:46 INFO - operating with platform_type : linux_ 13:50:46 INFO - Initialising browser for kraken test... 13:50:46 INFO - TEST-INFO | started process 1416 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxiEQuu/profile http://localhost:58884/getInfo.html) 13:50:50 INFO - PROCESS | 1416 | __metrics Screen width/height:1600/1200 13:50:50 INFO - PROCESS | 1416 | colorDepth:24 13:50:50 INFO - PROCESS | 1416 | Browser inner width/height: 1024/697 13:50:50 INFO - PROCESS | 1416 | __metrics 13:50:51 INFO - PROCESS | 1416 | 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" 13:50:51 INFO - PROCESS | 1416 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:50:52 INFO - TEST-INFO | 1416: exit 0 13:50:52 INFO - Browser initialized. 13:50:52 INFO - Running cycle 1/1 for kraken test... 13:50:52 INFO - TEST-INFO | started process 1495 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxiEQuu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:51:04 INFO - PROCESS | 1495 | RSS: Main: 158978048 13:51:04 INFO - PROCESS | 1495 | 13:51:07 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58884/tests/kraken/driver.html?testName=audio-beat-detection) 13:51:08 INFO - PROCESS | 1495 | RSS: Main: 217841664 13:51:08 INFO - PROCESS | 1495 | 13:51:10 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58884/tests/kraken/driver.html?testName=audio-dft) 13:51:11 INFO - PROCESS | 1495 | RSS: Main: 207118336 13:51:11 INFO - PROCESS | 1495 | 13:51:14 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58884/tests/kraken/driver.html?testName=audio-fft) 13:51:15 INFO - PROCESS | 1495 | RSS: Main: 271089664 13:51:15 INFO - PROCESS | 1495 | 13:51:17 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58884/tests/kraken/driver.html?testName=audio-oscillator) 13:51:18 INFO - PROCESS | 1495 | RSS: Main: 181604352 13:51:18 INFO - PROCESS | 1495 | 13:51:20 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58884/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:51:20 INFO - PROCESS | 1495 | RSS: Main: 185974784 13:51:20 INFO - PROCESS | 1495 | 13:51:23 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58884/tests/kraken/driver.html?testName=imaging-darkroom) 13:51:24 INFO - PROCESS | 1495 | RSS: Main: 222015488 13:51:24 INFO - PROCESS | 1495 | 13:51:28 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58884/tests/kraken/driver.html?testName=imaging-desaturate) 13:51:28 INFO - PROCESS | 1495 | RSS: Main: 218099712 13:51:28 INFO - PROCESS | 1495 | 13:51:31 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58884/tests/kraken/driver.html?testName=json-parse-financial) 13:51:32 INFO - PROCESS | 1495 | RSS: Main: 220823552 13:51:32 INFO - PROCESS | 1495 | 13:51:33 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58884/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:51:34 INFO - PROCESS | 1495 | RSS: Main: 209485824 13:51:34 INFO - PROCESS | 1495 | 13:51:36 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:51:37 INFO - PROCESS | 1495 | RSS: Main: 214667264 13:51:37 INFO - PROCESS | 1495 | 13:51:39 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:51:39 INFO - PROCESS | 1495 | RSS: Main: 210407424 13:51:39 INFO - PROCESS | 1495 | 13:51:42 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:51:43 INFO - PROCESS | 1495 | RSS: Main: 222875648 13:51:43 INFO - PROCESS | 1495 | 13:51:46 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:51:46 INFO - PROCESS | 1495 | RSS: Main: 225165312 13:51:46 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | Cycle 1(1): loaded http://localhost:58884/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58884/tests/kraken/driver.html?testName=ai-astar) 13:51:48 INFO - PROCESS | 1495 | RSS: Main: 267374592 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | __start_tp_report 13:51:48 INFO - PROCESS | 1495 | _x_x_mozilla_page_load 13:51:48 INFO - PROCESS | 1495 | _x_x_mozilla_page_load_details 13:51:48 INFO - PROCESS | 1495 | |i|pagename|runs| 13:51:48 INFO - PROCESS | 1495 | |0;ai-astar;105;113;115;115;114;113;114;113;113;113 13:51:48 INFO - PROCESS | 1495 | |1;audio-beat-detection;151;122;123;172;128;126;151;120;157;127 13:51:48 INFO - PROCESS | 1495 | |2;audio-dft;160;163;160;160;162;161;160;160;160;159 13:51:48 INFO - PROCESS | 1495 | |3;audio-fft;75;75;76;116;74;75;74;74;112;75 13:51:48 INFO - PROCESS | 1495 | |4;audio-oscillator;85;85;110;84;85;84;121;84;85;125 13:51:48 INFO - PROCESS | 1495 | |5;imaging-gaussian-blur;117;117;116;117;117;117;118;118;117;117 13:51:48 INFO - PROCESS | 1495 | |6;imaging-darkroom;145;146;146;146;146;145;146;146;146;145 13:51:48 INFO - PROCESS | 1495 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;94 13:51:48 INFO - PROCESS | 1495 | |8;json-parse-financial;63;62;64;63;63;64;66;62;65;65 13:51:48 INFO - PROCESS | 1495 | |9;json-stringify-tinderbox;66;67;66;66;67;66;67;66;66;66 13:51:48 INFO - PROCESS | 1495 | |10;stanford-crypto-aes;77;78;78;85;81;79;77;79;79;82 13:51:48 INFO - PROCESS | 1495 | |11;stanford-crypto-ccm;137;134;128;127;127;127;127;129;164;138 13:51:48 INFO - PROCESS | 1495 | |12;stanford-crypto-pbkdf2;170;168;165;169;168;168;164;164;165;166 13:51:48 INFO - PROCESS | 1495 | |13;stanford-crypto-sha256-iterative;66;69;68;68;65;65;65;66;66;65 13:51:48 INFO - PROCESS | 1495 | __end_tp_report 13:51:48 INFO - PROCESS | 1495 | __start_cc_report 13:51:48 INFO - PROCESS | 1495 | _x_x_mozilla_cycle_collect,1923 13:51:48 INFO - PROCESS | 1495 | __end_cc_report 13:51:48 INFO - PROCESS | 1495 | __startTimestamp1468961508658__endTimestamp 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | ------- Summary: start ------- 13:51:48 INFO - PROCESS | 1495 | Number of tests: 14 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.50 stddev:2.86 (2.5%) stddev-sans-first:0.87 13:51:48 INFO - PROCESS | 1495 | Values: 105.0 113.0 115.0 115.0 114.0 113.0 114.0 113.0 113.0 113.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:139.50 stddev:18.33 (13.1%) stddev-sans-first:18.80 13:51:48 INFO - PROCESS | 1495 | Values: 151.0 122.0 123.0 172.0 128.0 126.0 151.0 120.0 157.0 127.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.00 stddev:1.18 (0.7%) stddev-sans-first:1.24 13:51:48 INFO - PROCESS | 1495 | Values: 160.0 163.0 160.0 160.0 162.0 161.0 160.0 160.0 160.0 159.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.59 (22.1%) stddev-sans-first:17.36 13:51:48 INFO - PROCESS | 1495 | Values: 75.0 75.0 76.0 116.0 74.0 75.0 74.0 74.0 112.0 75.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:84.50 stddev:16.88 (20.0%) stddev-sans-first:17.52 13:51:48 INFO - PROCESS | 1495 | Values: 85.0 85.0 110.0 84.0 85.0 84.0 121.0 84.0 85.0 125.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 13:51:48 INFO - PROCESS | 1495 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 13:51:48 INFO - PROCESS | 1495 | Values: 145.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:51:48 INFO - PROCESS | 1495 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:1.34 (2.1%) stddev-sans-first:1.39 13:51:48 INFO - PROCESS | 1495 | Values: 63.0 62.0 64.0 63.0 63.0 64.0 66.0 62.0 65.0 65.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 13:51:48 INFO - PROCESS | 1495 | Values: 66.0 67.0 66.0 66.0 67.0 66.0 67.0 66.0 66.0 66.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:2.51 (3.2%) stddev-sans-first:2.49 13:51:48 INFO - PROCESS | 1495 | Values: 77.0 78.0 78.0 85.0 81.0 79.0 77.0 79.0 79.0 82.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#11] stanford-crypto-ccm Cycles:10 Average:133.80 Median:131.50 stddev:11.46 (8.7%) stddev-sans-first:12.09 13:51:48 INFO - PROCESS | 1495 | Values: 137.0 134.0 128.0 127.0 127.0 127.0 127.0 129.0 164.0 138.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:168.00 stddev:2.16 (1.3%) stddev-sans-first:1.94 13:51:48 INFO - PROCESS | 1495 | Values: 170.0 168.0 165.0 169.0 168.0 168.0 164.0 164.0 165.0 166.0 13:51:48 INFO - PROCESS | 1495 | 13:51:48 INFO - PROCESS | 1495 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.49 (2.3%) stddev-sans-first:1.58 13:51:48 INFO - PROCESS | 1495 | Values: 66.0 69.0 68.0 68.0 65.0 65.0 65.0 66.0 66.0 65.0 13:51:48 INFO - PROCESS | 1495 | -------- Summary: end -------- 13:51:48 INFO - PROCESS | 1495 | 13:51:49 INFO - TEST-INFO | 1495: exit 0 13:51:49 INFO - TEST-OK | kraken | took 63662ms 13:51:49 INFO - SUITE-END | took 1183s 13:51:49 INFO - Completed test suite (00:19:43) 13:51:49 INFO - javascript benchmark 13:51:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2590.689117305923, 2596.4677241165564, 2622.462395208982, 2632.828866123702, 2634.6179803928417, 4668.719876807394, 4738.123447162273, 4740.4548476925165, 4762.119007983521, 4782.378290822188, 4731.030138821542, 4751.239801631739, 4759.547842954922, 4793.137235297654, 4793.640476964224, 4331.696781225308, 4340.348947657861, 4430.291153415455, 4458.175237581042, 4516.683832131752, 4442.111577684476, 4463.129689710508, 4489.5285994970545, 4498.910021799562, 4533.138703646313, 4312.310030395121, 4328.696991210617, 4364.323529852878, 4374.147041326911, 4390.517043125254, 4282.036541778106, 4291.35629655551, 4304.3113101903855, 4327.0913108247305, 4343.457067866517, 4314.633256173229, 4317.41714868495, 4318.589733975267, 4354.303311470149, 4374.0595771908875, 4622.953770462286, 4703.976480117612, 4722.76386180693, 4760.619150467955, 4770.212914869043, 5121.667091639031, 5158.4067832199025, 5174.715390653512, 5180.119379705459, 5184.481551844823, 5163.251328557376, 5173.120569503193, 5201.40183878855, 5212.661177023481, 5215.556677682402, 4617.399738034068, 4620.099080679235, 4645.767711614406, 4680.53194680536, 4760.166970780099, 5143.331366922351, 5157.613179011589, 5178.378594568676, 5188.325517682677, 5214.97392513035, 4933.062718083553, 4947.0, 4978.452370239271, 4998.05037042968, 5001.274815151855, 4938.456769755324, 5062.291279220912, 5081.0, 5096.668716533413, 5155.561777248976, 5056.039352523009, 5064.290999260106, 5065.7720402581235, 5090.10923088458, 5146.588272938156, 5049.444561098277, 5062.924056139161, 5072.315237442972, 5081.5426611605135, 5086.745662716849, 4149.398337241083, 4200.264953633102, 4203.558626344251, 4250.766207858597, 4250.957490425118, 3718.163413232055, 3747.437884317374, 3773.396256598931, 3775.0, 3775.8489660413275, 4360.324149756792, 4372.781360931941, 4380.036391993756, 4382.890427739332, 4393.538678438782, 5111.412187598399, 5112.258722485293, 5115.335006449138, 5124.077666020077, 5159.097157997336, 4402.955970440319, 4403.691741578059, 4404.647628189737, 4438.822447102144, 4456.398386217884, 4455.821767129279, 4516.36770852081, 4547.522510136454, 4556.794944227518, 4571.291449825216, 4834.903301933871, 4837.540433658867, 4842.5157484251995, 4878.634102442331, 4884.462709102067, 5058.418281897481, 5071.0, 5097.464766199644, 5116.565091967075, 5117.053345131163, 4708.482066972561, 4749.691270067435, 4751.144793937125, 4754.976225118852, 4873.658843880894, 4983.152864013199, 4983.775730092227, 4986.476419975996, 4994.550490455877, 5054.21659642756, 5003.54968052877, 5029.572486338702, 5035.219540971156, 5041.621878359211, 5088.516590923857, 4791.065742180242, 4878.463369029333, 4930.778114984906, 4942.258661200849, 4950.653454258168, 4953.653244272867, 4988.251762235694, 4993.850184494471, 4995.0, 5045.949540504548, 4698.647601429838, 4894.216925291937, 4902.632302577393, 4905.656604037826, 4960.429550601582, 4980.427250866197, 5034.974825125997, 5043.193089105725, 5052.343195384577, 5071.4421413645205, 4947.233178857283, 4964.429090654477, 4967.05625931072, 4967.230079337708, 5012.423571289202], "value": 4666.142848821072, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.209966545164, 599.0505049496546, 606.1392822192486, 612.9793893167875, 622.5268795715255, 40166.598334016686, 42328.36507452387, 42353.36469952949, 42399.36400953988, 46243.53756462434, 43432.13135737283, 43879.3418098728, 43937.34093988593, 43987.78006109973, 44020.0, 7540.2082781308, 7935.0, 8055.6777728890775, 8067.193280671944, 10220.182385409149, 10089.798204035915, 10474.528646210902, 10477.947610261976, 10519.316244444086, 10525.157987361032, 10569.0, 10616.78766424671, 10619.25665203436, 10622.893771062307, 10628.415437150954, 7599.620018999056, 7748.845023099563, 7757.263060009346, 10190.541425635864, 10482.266241363108, 10005.799884002352, 10378.169746420268, 10379.169666426744, 10391.48042597867, 10391.636292729718, 40127.0, 40513.79743101266, 40633.79683101566, 40647.59352406497, 40735.388969165484, 2983.298924752681, 3124.5313203019728, 3157.589513363248, 3333.21669407689, 3448.758586898918, 30611.09194626507, 30678.539821902465, 30771.0, 30901.0, 31175.84412077925, 3182.6658200888805, 3211.020638705194, 3219.0664707234873, 3236.0453666168537, 3257.9185520361943, 190905.0, 190910.0, 191739.0, 276311.0, 276422.0, 32866.342673146406, 33745.49381759275, 55657.44342556604, 56194.15708764372, 56300.0, 35190.29619407649, 35249.82375088108, 35365.64634353675, 35367.292654146775, 35414.29171416609, 13292.20246785196, 13477.0, 13497.190168589916, 13499.055066145473, 13504.324783760772, 10429.0, 11164.832527512093, 11561.76876462466, 11612.88387116118, 11822.34977076269, 8064.596770161468, 8066.274035336848, 8085.272325490734, 8143.226393492609, 8163.408135413656, 3125.4530457169903, 3152.9122452753763, 3175.0, 3189.059227527885, 3191.7446604271604, 929.6606738540519, 959.7648576098901, 985.6008316631633, 987.3483500889379, 1007.1439276614818, 29491.85254073716, 29575.26061848471, 29748.702512975025, 29903.551446728317, 30195.69804301973, 981.8085473332775, 1014.4775440648073, 1015.7663737340453, 1043.0456132638703, 1097.2429023973432, 15200.163990980382, 15473.381064757508, 15655.84344156544, 15827.208639567976, 15954.521364359089, 785494.0, 797859.0, 803493.0, 804084.0, 804706.0, 49142.26286605704, 49665.50334496681, 49710.75144624326, 49865.25202121971, 50096.74951625219, 1101.5593762495066, 1161.802493576111, 1179.0, 1205.5177928828364, 1210.4069006186905, 28377.858110709316, 29545.704542954296, 30006.0, 30522.694773052874, 30568.235794105327, 43269.350959735, 48161.5183848157, 50020.24969625531, 50020.49979500158, 55505.722471389, 354544.0, 357399.0, 358515.0, 360485.0, 361008.0, 32578.0, 32878.34243315073, 35104.82447587746, 40236.39645405263, 43100.13799723925, 43431.565684344016, 43739.0, 44020.33969490396, 44201.33697994468, 44799.0, 37339.813300933325, 37489.81255093707, 38323.616763832004, 38450.807745961094, 38582.2283554333, 35805.82097089498, 38222.80888595539, 39222.2155556893, 39497.80251098726, 39758.2048359037, 36629.0, 37393.2521349566, 37568.81215593904, 37621.0, 38037.80981095077, 30096.742744293828, 32174.83912580422, 34507.0, 35088.82455587706, 35118.29763404769, 25568.616470752582, 25644.358891027874, 25841.0, 25891.19509724221, 25950.740492594832], "value": 18007.32994352288, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.88382777956184, 363.19552191894945, 370.9387950988087, 373.7794701126335, 384.3312636013338, 13071.346432678363, 13749.0, 15036.924815375907, 17547.0, 17552.0, 16143.112128832909, 17234.913825430856, 17641.82358176418, 17647.02941338229, 17661.20524576394, 5085.872853178673, 5208.062548741225, 5239.13554263546, 5245.659032162897, 5246.606504512158, 3846.499955005845, 5871.295444546639, 6158.84602884927, 6163.537734669895, 6270.526426289818, 6032.426919442668, 6068.908966365508, 6192.040233763774, 6217.285012223584, 6234.283057448383, 6140.969295153518, 6173.69131543421, 6176.505879529627, 6225.719842607094, 6234.781782637631, 5206.140986737184, 5359.08895487768, 5359.598030147716, 5387.1380579107545, 5445.53712934401, 21501.67747483789, 21713.457163570878, 21889.452763680874, 22084.88957555202, 22126.22558210471, 1895.1282410091374, 2021.3632705697805, 2071.9585608287903, 2284.2119468783244, 2333.6499525071204, 12790.936045319806, 13499.595012149652, 13508.121972071882, 13917.721645567133, 13976.720465590632, 1713.5705487482173, 1728.4209789720333, 1762.7972783129976, 1812.8640351973522, 1827.41522712732, 70637.64681176613, 73235.07059717605, 73796.0, 74760.99999999946, 75005.0, 9372.8594071089, 9443.952780236192, 9704.563294651616, 9844.3601165924, 10060.295779295526, 13178.406971686298, 13292.069555131242, 13317.33413329349, 13325.866741332657, 13341.19952802835, 7349.30181632744, 7401.0, 7415.295546923034, 7535.058117735283, 7568.205338439385, 7152.821179470555, 7242.420606351585, 7311.451641126937, 7419.591922444318, 7442.218567050381, 6040.848978775478, 6186.319504854462, 6243.469305109116, 6248.0, 6265.9402383717, 1747.9475615731549, 1752.2202619834306, 1795.7216631421888, 1819.3814103204975, 1849.556106534422, 846.3652260804397, 882.43965082172, 886.8093359927647, 924.6209054287709, 961.6489981156827, 18139.90930045341, 18382.356617518322, 18421.0, 18686.813131868777, 18723.438296851116, 879.331707901999, 895.5656506594297, 904.9366544341833, 932.9860052099224, 943.8537026760863, 9889.554970026365, 10315.948420257851, 10324.22568307365, 10477.580896764044, 10480.0, 373177.0, 375535.0, 379521.0, 380938.0, 380956.0, 44312.73242076513, 63396.68301658462, 63528.68235658885, 70076.64961675159, 71558.28441715516, 811.4725428471422, 819.3158712475031, 835.3150416658282, 835.5279267214005, 839.693511868176, 16439.09584972838, 16621.83378166203, 16727.665446690753, 16905.239264233383, 16978.745318820474, 34448.48327275143, 35930.28139437144, 35953.460698089024, 36284.45573316349, 36704.63295367012, 44128.33807492826, 47693.0, 47775.76112119556, 48137.0, 49058.50941490539, 4554.954450455453, 5985.491233245223, 7548.698052077855, 7565.130010048915, 8063.395245356739, 12390.132690711564, 12465.189762665397, 12556.811647825107, 12587.370631468573, 12878.935605321913, 10711.678649640524, 10796.67609971702, 11025.393603351893, 11027.338359698444, 11163.832542511705, 10463.0, 10677.786444271227, 10690.946545267223, 10694.0, 11455.198136130724, 9972.700818975152, 10294.073533382032, 10315.535800889129, 10354.378737275787, 10533.78932421332, 7341.151130788586, 7497.212792656911, 7626.0, 7687.923120768721, 7764.534127952341, 9999.60001599957, 10095.697129085846, 10614.416207108374, 11034.889651103707, 11135.220534562503], "value": 8484.364180109238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.4658256571217, 1436.2387934394771, 1502.8497150284966, 1713.0321368426846, 1731.5584525945874, 7568.356689681377, 7948.682052717884, 8025.719099831507, 8033.598320083995, 8036.236557527026, 6771.661416929146, 7523.285287897655, 7774.455788094836, 7786.688532458695, 7919.524828510293, 2191.3097374327117, 2215.4129155773735, 2221.5445833604153, 2227.10915633747, 2236.1837929155904, 2221.1115553778454, 2231.5760005598963, 2262.6606009098687, 2264.7169103862016, 2265.9433514162197, 1881.6801143805517, 1948.3667807962397, 1954.7165660979222, 1960.7647082350138, 2121.5332626822074, 2168.56628674265, 2195.297504798465, 2200.7579166291775, 2205.7794220577975, 2216.3240211735415, 2176.5102851858364, 2184.322859913432, 2191.3535507025467, 2250.076184861936, 2254.8985295661732, 7870.921290787076, 7980.122186559473, 7981.201879812031, 7985.081715602722, 7985.840283194304, 4641.814327426899, 4806.663533552653, 4810.206315957863, 4810.8316208932865, 4811.566958973674, 2807.9438411231863, 2873.281679580105, 2907.185987923385, 2921.5033444314304, 2952.5866378706996, 832.6045128563884, 855.4867079752099, 861.6467248428177, 862.3790870573177, 867.7677697669325, 10526.0, 10581.31221470602, 10584.153267738639, 10608.15134789215, 10609.84085238722, 3947.210557888434, 4345.0, 4352.107817897323, 4364.039911219526, 4365.214261432909, 9090.545472726337, 9101.81796364069, 9135.177833994972, 9152.542372881462, 9159.725208243763, 4739.526047395302, 4745.477997420281, 4747.145513807548, 4749.525047495292, 4763.666543341933, 4269.380939763779, 4320.611144996966, 4334.739915605074, 4372.300431930876, 4392.121575684876, 3658.286634106377, 3683.5211422515436, 3737.439384092408, 3780.7353485255767, 3792.0, 1208.546794951893, 1209.6854817747274, 1214.1379620469388, 1230.56930074475, 1236.6722818453118, 706.2231545300128, 796.6335485676537, 798.7364169823944, 804.7143264141263, 878.6529320918202, 5213.817516386908, 5372.3016007919505, 5420.403755586883, 5437.592180586472, 5447.782088716407, 864.5072308783933, 898.8876390451194, 905.090384163911, 909.3270979475141, 913.8857642794651, 3474.826258687106, 3639.6178401267484, 3651.817409129586, 3686.0600546860915, 3719.795411252353, 9417.0, 9693.51532423376, 9825.754356141153, 9834.557444914995, 9848.60605575769, 9713.271504637181, 9720.368176068674, 9736.269779766546, 9747.805043899083, 9761.121499065119, 7415.517991330654, 7510.211427800112, 7548.320651141424, 7556.546607203585, 7564.054493188351, 5193.974030129825, 5390.164524498709, 5503.394626591152, 5539.0, 5666.744996475251, 8604.354673399395, 8943.284537237136, 8945.821083578423, 9051.773705657148, 9146.31402644791, 9023.684171053981, 9135.269178465574, 9209.447433154031, 9243.676471323472, 9259.953700231456, 7237.927620723725, 7389.482736208413, 7448.143463501767, 7513.436492262993, 7559.470837041355, 7664.233576642291, 7665.156832748505, 7717.961410192913, 7725.343345815444, 7726.575038372944, 6572.737090516326, 6583.539152259296, 6695.263521012782, 6698.430633396216, 6790.796276111724, 6611.537192396486, 6669.56560855294, 6693.564918280296, 6719.0, 6773.0, 6538.8365290868105, 6601.834954126185, 6624.668766561749, 6629.07192992991, 6630.9336906630315, 4278.914421711611, 4454.510003899503, 4588.862334129982, 4628.814847406066, 4630.143423466669, 4516.706414083074, 4599.586037256529, 4605.700629459075, 4607.769611519344, 4669.18289299378], "value": 4191.466068497112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.78963987369593, 293.1001824399093, 296.36873459531193, 305.1166871905833, 309.1267170244061, 4582.4730156032065, 4856.708597484149, 4943.802247910079, 4981.252812078181, 4985.326980857583, 4762.0, 4889.3888263967, 4907.975460122703, 4908.901821963558, 4926.852194434173, 3639.2175682228317, 3790.431435284702, 3854.479645247891, 3874.372763439362, 3904.824282907262, 3749.362608356586, 3851.268259030789, 3867.7292589518743, 3880.2433525462548, 3893.922121557567, 3823.5220597425323, 3857.1707082977155, 3864.574896761354, 3864.9757814179266, 3870.748401353903, 3862.9227415451674, 3864.3044252034483, 3868.574456809749, 3877.166409222031, 3883.456316115746, 3187.474066778979, 3325.334933013407, 3329.583802024747, 3365.4615261558033, 3456.2914602506676, 4728.95271047292, 4743.596794272491, 4747.548982846659, 4761.071765934802, 4769.785359658824, 4157.91684166315, 4305.461817272841, 4309.052008558112, 4352.717073390219, 4361.476622805252, 5111.488851114896, 5117.616178786606, 5132.0, 5143.254228136899, 5188.143956247215, 3642.963570364289, 3659.1949771050326, 3662.4434615288396, 3664.8534058637624, 3666.4866918631415, 5247.475252474722, 5315.302856691293, 5395.163749618815, 5423.728813559345, 5425.891482170335, 3699.204670995749, 3705.6479634434686, 3715.275521273326, 3720.2373513429684, 3722.8696995605023, 3746.9437958430644, 3774.490443790053, 3781.111438811877, 3783.5838057813617, 3788.0908581940134, 4332.176886391576, 4338.891527711832, 4345.891352716144, 4348.869533913988, 4351.586599273064, 5053.166227572409, 5091.592672586185, 5108.0, 5125.666831655931, 5190.143626301618, 1287.028293638297, 1291.3156027305542, 1301.6485548901744, 1304.0024381348276, 1305.7388522295578, 805.9120187746495, 837.3552364679284, 840.8864803251482, 843.1442086282428, 846.8137009857821, 4125.143685341548, 4159.729617574848, 4337.240982827992, 4473.932891006638, 4502.4371953505815, 5316.069687804618, 5321.866953326198, 5331.413544510101, 5332.68003919766, 5341.225522299244, 4557.954420455753, 4561.657875659339, 4562.224421848294, 4578.427696537933, 4590.862274131781, 2723.891044358243, 2740.3012231880743, 2796.398774263543, 2797.790165737578, 2807.719228077176, 4961.652684312064, 4967.130752118364, 4975.303457515951, 4981.875453113628, 5019.498050194951, 5367.329083864517, 5387.17173483924, 5388.407275199804, 5396.514313711785, 5411.134218525017, 4866.367372241586, 4954.653174277766, 4957.776900039579, 4964.503549645006, 4973.975130124326, 5238.135707608202, 5245.66235609917, 5247.317848679647, 5262.789488420573, 5332.173513105475, 4951.727654978867, 5190.740462976912, 5207.901298727251, 5219.765110569958, 5227.843164705065, 4943.406791185081, 4951.876203094951, 4986.252062190701, 5017.598592112695, 5021.271915572294, 3687.2256826065754, 3716.461113138526, 3728.800007995296, 3740.8877733668032, 3763.962360376361, 5201.323827902348, 5316.149416093406, 5328.653637513549, 5364.946350536445, 5369.677819330853, 5091.0, 5118.590512758896, 5119.872003199801, 5121.283020377225, 5132.9743351283005, 5055.69665820052, 5069.645124841226, 5097.286379906742, 5107.719075450737, 5113.4375218726655, 4752.524747525358, 5001.8499445016705, 5017.247412888096, 5026.421961474477, 5027.416363845377, 4897.755112244445, 5061.519155680205, 5129.281480815174, 5152.407473140637, 5153.45888681683, 4938.728369939688, 4959.231319145538, 4974.17926042199, 4978.182963218472, 5001.1248031593595], "value": 3851.778940685893, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.1906512001838, 651.5504302031595, 652.0512654088302, 658.4469046001358, 701.7929710735333, 12045.819312710306, 13080.73838523229, 13544.05191636586, 13643.044986850911, 13664.863351366495, 12972.0, 13194.340282985837, 13195.934020329885, 13211.14127581709, 13213.942884569211, 614.1524695919625, 767.2710924621632, 775.2518819339336, 794.8291117409756, 797.4258533855643, 788.1291173253529, 788.8106854354942, 791.635847510146, 791.7189397763798, 791.7505985614513, 834.2575108153748, 836.3977935886152, 836.477201748907, 836.5357226739158, 838.0153319849184, 642.8425035866219, 796.1958421993799, 816.3795515408273, 819.2626636027563, 821.6836517940576, 604.4590091867798, 604.9001914684072, 606.4420732925719, 609.8261995331309, 609.9359567245464, 13078.346082695922, 13114.672133196651, 13126.343682815821, 13133.60599182026, 13138.080334376598, 11085.612003579916, 11259.43702814864, 11446.713832154179, 11618.244814087057, 11641.70895727605, 10118.949405252926, 10322.0, 10348.637797677045, 10395.428251446167, 10607.628732994308, 4020.6783457323345, 4037.1320166164414, 4037.8788636340955, 4059.451973983504, 4119.814608342632, 62926.37073629251, 63014.0, 63987.0, 65235.0, 65501.67249163771, 9192.908070919206, 9246.26029917605, 9284.72145835626, 9629.0, 9653.420794752337, 17455.56361090983, 17477.475675729747, 17485.03832289211, 17522.91238543799, 17533.123343832758, 6598.406143447017, 6618.63597502142, 6696.933030669728, 6754.729810807513, 6868.725250989904, 3668.211334563082, 3703.8703645372284, 3720.311742327678, 3720.627937206312, 3779.6976241900584, 2647.1661426650544, 2734.849583272939, 2735.7811375089946, 2738.7398197171237, 2740.8355498670144, 393.73029474809846, 467.752091391563, 493.13454886673657, 494.868859752166, 532.579262382721, 542.866997585594, 560.6944215402615, 579.8550362409397, 593.3176846626415, 597.5232660621699, 7484.550926944401, 7748.961255193801, 7828.960855195802, 8285.0, 8657.480551166951, 587.7766448749447, 588.6350462713145, 589.4400319696219, 602.9005214139618, 611.5596770325359, 3797.7721336719887, 3947.913082075268, 4038.8586399476467, 4042.535108462506, 4055.858044968471, 105045.0, 106959.0, 107793.0, 109008.0, 110565.44717276363, 89788.5510572443, 91176.0, 91577.0, 94481.5275923616, 95341.52329238449, 45751.856203594514, 48414.0, 48646.513534864906, 49453.25820112701, 49543.0, 8256.83486330282, 8391.118932511998, 8480.448770829877, 8517.659293628185, 8519.020312664123, 41894.37158442565, 42780.0, 43678.11519470188, 43684.12631747411, 43969.12061758811, 36764.63235367612, 56537.71731141318, 64303.03545446729, 65808.0, 66992.66503667645, 11481.770364592829, 11608.941955290169, 11792.52829886795, 11804.645860624196, 12253.509859605516, 8942.50816205115, 12912.548060817826, 12979.480820767441, 13585.184888906697, 13673.726525469236, 7561.4706970513735, 11095.0, 11396.145289103517, 11722.472488738198, 12061.879381206427, 11491.425428728697, 11519.76960460803, 11660.941695291469, 11980.101492387932, 12129.69675758113, 11220.1023918088, 11733.0, 11763.176577639482, 11782.057435405319, 11984.700382490159, 9906.455144967096, 10064.44645544502, 10348.482575871327, 10390.376577405379, 10452.529636166239, 9454.480003599869, 9620.13418792312, 10114.195737155978, 10138.267223590115, 10158.695239142837], "value": 6452.784581448978, "unit": "score"}], "name": "dromaeo_css", "value": 6483.485478277168, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 115.0, 115.0, 114.0, 113.0, 114.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 123.0, 172.0, 128.0, 126.0, 151.0, 120.0, 157.0, 127.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 160.0, 162.0, 161.0, 160.0, 160.0, 160.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 116.0, 74.0, 75.0, 74.0, 74.0, 112.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 110.0, 84.0, 85.0, 84.0, 121.0, 84.0, 85.0, 125.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 63.0, 63.0, 64.0, 66.0, 62.0, 65.0, 65.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 85.0, 81.0, 79.0, 77.0, 79.0, 79.0, 82.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 128.0, 127.0, 127.0, 127.0, 127.0, 129.0, 164.0, 138.0], "value": 133.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 165.0, 169.0, 168.0, 168.0, 164.0, 164.0, 165.0, 166.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 68.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1522.3, "alertThreshold": 2.0}]} 13:51:49 INFO - Return code: 0 13:51:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:51:49 INFO - # TBPL SUCCESS # 13:51:49 INFO - Running post-action listener: _resource_record_post_action 13:51:49 INFO - [mozharness: 2016-07-19 20:51:49.948404Z] Finished run-tests step (success) 13:51:49 INFO - Running post-run listener: _resource_record_post_run 13:51:50 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 536428544; Read time: 0; Write time: 395148 13:51:50 INFO - TinderboxPrint: CPU usage
13.0% 13:51:50 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 13:51:50 INFO - TinderboxPrint: I/O write bytes / time
536,428,544 / 395,148 13:51:50 INFO - TinderboxPrint: CPU idle
8,334.5 (87.0%) 13:51:50 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 13:51:50 INFO - TinderboxPrint: Swap in / out
0 / 0 13:51:50 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246362112; Read time: 0; Write time: 219116 13:51:50 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 289726464; Read time: 0; Write time: 176008 13:51:50 INFO - Running post-run listener: _upload_blobber_files 13:51:50 INFO - Blob upload gear active. 13:51:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:50 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 13:51:50 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:51:50 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:51:51 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:51 INFO - (blobuploader) - INFO - Done attempting. 13:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:51:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:52 INFO - (blobuploader) - INFO - Done attempting. 13:51:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:51:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:52:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:52:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:52:04 INFO - (blobuploader) - INFO - Done attempting. 13:52:04 INFO - (blobuploader) - INFO - Iteration through files over. 13:52:04 INFO - Return code: 0 13:52:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:52:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:52:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4518c7f54c0f68e51835533629346580c24b5358e31cce33aeadcf07eedc4e217b1121c53b0abc45863cac9bca7d86a65f67d6a9a0cf6b509847f666bb4fb58f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/79ca8040498d1f073fc25b26fe6e78233aa26b129856b8298b12ae2ab380815e0511dfd4157be12d67cc3616e2f0974f4715cbb20fbf66214457237fd9378b34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95606912e585d6d942fcd1e39209336b4db2cf9272bb6689ce55f933d1f0e2d215912ae15af8abb8f38df70f0c5b84eb283d4a1c16000f75d0aa5f939fc1ea50"} 13:52:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:52:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:52:04 INFO - Contents: 13:52:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4518c7f54c0f68e51835533629346580c24b5358e31cce33aeadcf07eedc4e217b1121c53b0abc45863cac9bca7d86a65f67d6a9a0cf6b509847f666bb4fb58f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/79ca8040498d1f073fc25b26fe6e78233aa26b129856b8298b12ae2ab380815e0511dfd4157be12d67cc3616e2f0974f4715cbb20fbf66214457237fd9378b34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95606912e585d6d942fcd1e39209336b4db2cf9272bb6689ce55f933d1f0e2d215912ae15af8abb8f38df70f0c5b84eb283d4a1c16000f75d0aa5f939fc1ea50"} 13:52:04 INFO - Running post-run listener: copy_logs_to_upload_dir 13:52:04 INFO - Copying logs to upload dir... 13:52:04 INFO - mkdir: /builds/slave/test/build/upload/logs 13:52:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.488545 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-19 13:52:04.699288) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 13:52:04.702314) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4518c7f54c0f68e51835533629346580c24b5358e31cce33aeadcf07eedc4e217b1121c53b0abc45863cac9bca7d86a65f67d6a9a0cf6b509847f666bb4fb58f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/79ca8040498d1f073fc25b26fe6e78233aa26b129856b8298b12ae2ab380815e0511dfd4157be12d67cc3616e2f0974f4715cbb20fbf66214457237fd9378b34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95606912e585d6d942fcd1e39209336b4db2cf9272bb6689ce55f933d1f0e2d215912ae15af8abb8f38df70f0c5b84eb283d4a1c16000f75d0aa5f939fc1ea50"} build_url:https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011127 build_url: 'https://queue.taskcluster.net/v1/task/Zl1Nz9_dTdusqknsZ3CFSg/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/Autoland-Non-PGO/sha512/4518c7f54c0f68e51835533629346580c24b5358e31cce33aeadcf07eedc4e217b1121c53b0abc45863cac9bca7d86a65f67d6a9a0cf6b509847f666bb4fb58f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/79ca8040498d1f073fc25b26fe6e78233aa26b129856b8298b12ae2ab380815e0511dfd4157be12d67cc3616e2f0974f4715cbb20fbf66214457237fd9378b34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95606912e585d6d942fcd1e39209336b4db2cf9272bb6689ce55f933d1f0e2d215912ae15af8abb8f38df70f0c5b84eb283d4a1c16000f75d0aa5f939fc1ea50"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 13:52:04.727813) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:52:04.728148) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1468958434.350150-2078979623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004411 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:52:04.811179) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 13:52:04.811527) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 13:52:04.811878) ========= ========= Total master_lag: 0.19 =========