builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1468239791.35 results: success (0) buildid: 20160711021319 builduid: b81e2b480c9746b681c6bdce7fb3ff22 revision: 0136b1e24160 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.350667) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.351198) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.351615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.368894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.369298) ========= 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=00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 05:23:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2016-07-11 05:23:11 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125344 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.513141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.513681) ========= 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=00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017213 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:11.566474) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 05:23:11.566872) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0136b1e24160 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0136b1e24160 --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=00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 05:23:11,945 Setting DEBUG logging. 2016-07-11 05:23:11,945 attempt 1/10 2016-07-11 05:23:11,945 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0136b1e24160?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 05:23:13,226 unpacking tar archive at: fx-team-0136b1e24160/testing/mozharness/ program finished with exit code 0 elapsedTime=1.667304 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 05:23:13.251434) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:13.252205) ========= script_repo_revision: 0136b1e24160 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:13.253143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:13.253767) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 05:23:13.267659) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-11 05:23:13.268064) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:23:13 INFO - MultiFileLogger online at 20160711 05:23:13 in /builds/slave/test 05:23:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:23:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:13 INFO - {'append_to_log': False, 05:23:13 INFO - 'base_work_dir': '/builds/slave/test', 05:23:13 INFO - 'blob_upload_branch': 'Fx-Team', 05:23:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:13 INFO - 'branch': 'Fx-Team', 05:23:13 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:13 INFO - 'config_files': ('talos/linux_config.py',), 05:23:13 INFO - 'default_actions': ('clobber', 05:23:13 INFO - 'read-buildbot-config', 05:23:13 INFO - 'download-and-extract', 05:23:13 INFO - 'populate-webroot', 05:23:13 INFO - 'create-virtualenv', 05:23:13 INFO - 'install', 05:23:13 INFO - 'run-tests'), 05:23:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:13 INFO - 'download_minidump_stackwalk': True, 05:23:13 INFO - 'download_symbols': 'ondemand', 05:23:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:13 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:13 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:13 INFO - 'installer_path': 'installer.exe', 05:23:13 INFO - 'log_level': 'info', 05:23:13 INFO - 'log_name': 'talos', 05:23:13 INFO - 'log_to_console': True, 05:23:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:23:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:23:13 INFO - 'opt_config_files': (), 05:23:13 INFO - 'pip_index': False, 05:23:13 INFO - 'sps_profile': False, 05:23:13 INFO - 'sps_profile_interval': 0, 05:23:13 INFO - 'suite': 'dromaeojs', 05:23:13 INFO - 'system_bits': '32', 05:23:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:13 INFO - 'title': 'talos-linux64-ix-016', 05:23:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:13 INFO - 'use_talos_json': True, 05:23:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:13 INFO - 'work_dir': 'build'} 05:23:13 INFO - ##### 05:23:13 INFO - ##### Running clobber step. 05:23:13 INFO - ##### 05:23:13 INFO - Running pre-action listener: _resource_record_pre_action 05:23:13 INFO - Running main action method: clobber 05:23:13 INFO - rmtree: /builds/slave/test/build 05:23:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:14 INFO - Running post-action listener: _resource_record_post_action 05:23:14 INFO - ##### 05:23:14 INFO - ##### Finished clobber step (success) 05:23:14 INFO - ##### 05:23:14 INFO - ##### 05:23:14 INFO - ##### Running read-buildbot-config step. 05:23:14 INFO - ##### 05:23:14 INFO - Running pre-action listener: _resource_record_pre_action 05:23:14 INFO - Running main action method: read_buildbot_config 05:23:14 INFO - Using buildbot properties: 05:23:14 INFO - { 05:23:14 INFO - "project": "", 05:23:14 INFO - "product": "firefox", 05:23:14 INFO - "script_repo_revision": "production", 05:23:14 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:23:14 INFO - "repository": "", 05:23:14 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:23:14 INFO - "buildid": "20160711021319", 05:23:14 INFO - "pgo_build": "True", 05:23:14 INFO - "basedir": "/builds/slave/test", 05:23:14 INFO - "buildnumber": 1318, 05:23:14 INFO - "slavename": "talos-linux64-ix-016", 05:23:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:23:14 INFO - "platform": "ubuntu64_hw", 05:23:14 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:23:14 INFO - "branch": "fx-team", 05:23:14 INFO - "repo_path": "integration/fx-team", 05:23:14 INFO - "revision": "0136b1e24160", 05:23:14 INFO - "stage_platform": "linux64-pgo", 05:23:14 INFO - "builduid": "b81e2b480c9746b681c6bdce7fb3ff22", 05:23:14 INFO - "slavebuilddir": "test" 05:23:14 INFO - } 05:23:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:23:14 INFO - Running post-action listener: _resource_record_post_action 05:23:14 INFO - ##### 05:23:14 INFO - ##### Finished read-buildbot-config step (success) 05:23:14 INFO - ##### 05:23:14 INFO - ##### 05:23:14 INFO - ##### Running download-and-extract step. 05:23:14 INFO - ##### 05:23:14 INFO - Running pre-action listener: _resource_record_pre_action 05:23:14 INFO - Running main action method: download_and_extract 05:23:14 INFO - mkdir: /builds/slave/test/build/tests 05:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:14 INFO - https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:23:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:14 INFO - Downloaded 1361 bytes. 05:23:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:14 INFO - Using the following test package requirements: 05:23:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:23:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:23:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'jsshell-linux-x86_64.zip'], 05:23:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:23:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:23:14 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:23:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:23:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:23:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:14 INFO - https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:23:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:15 INFO - Downloaded 24304453 bytes. 05:23: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'] 05:23: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 05:23:17 INFO - Return code: 0 05:23:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:17 INFO - https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:23:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:18 INFO - Downloaded 24304453 bytes. 05:23:18 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'] 05:23:18 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 05:23:19 INFO - Return code: 0 05:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:23:19 INFO - https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:23:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:20 INFO - Downloaded 14403333 bytes. 05:23:20 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'] 05:23:20 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 05:23:20 INFO - Return code: 0 05:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:23:20 INFO - https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:23:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/coEJbLhqTci38c6RWN6rzw/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 05:23:23 INFO - Downloaded 60469528 bytes. 05:23:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:23 INFO - mkdir: /builds/slave/test/properties 05:23:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:23 INFO - Contents: 05:23:23 INFO - build_url:https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:24 INFO - Running post-action listener: _resource_record_post_action 05:23:24 INFO - Running post-action listener: set_extra_try_arguments 05:23:24 INFO - ##### 05:23:24 INFO - ##### Finished download-and-extract step (success) 05:23:24 INFO - ##### 05:23:24 INFO - ##### 05:23:24 INFO - ##### Running populate-webroot step. 05:23:24 INFO - ##### 05:23:24 INFO - Running pre-action listener: _resource_record_pre_action 05:23:24 INFO - Running main action method: populate_webroot 05:23:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:24 INFO - '--tppagecycles', 05:23:24 INFO - '7'], 05:23:24 INFO - 'tests': ['tsvgm']}}, 05:23:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['damp', 'tps']}, 05:23:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'tests': ['damp', 'tps']}, 05:23:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['dromaeo_dom']}, 05:23:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['basic_compositor_video']}, 05:23:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['a11yr', 05:23:24 INFO - 'ts_paint', 05:23:24 INFO - 'tpaint', 05:23:24 INFO - 'sessionrestore', 05:23:24 INFO - 'sessionrestore_no_auto_restore', 05:23:24 INFO - 'tabpaint']}, 05:23:24 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:24 INFO - 'ts_paint', 05:23:24 INFO - 'tpaint', 05:23:24 INFO - 'sessionrestore', 05:23:24 INFO - 'sessionrestore_no_auto_restore', 05:23:24 INFO - 'tabpaint']}, 05:23:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:24 INFO - 'ts_paint', 05:23:24 INFO - 'tpaint', 05:23:24 INFO - 'sessionrestore', 05:23:24 INFO - 'sessionrestore_no_auto_restore', 05:23:24 INFO - 'tabpaint']}, 05:23:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:24 INFO - 'ts_paint', 05:23:24 INFO - 'tpaint', 05:23:24 INFO - 'sessionrestore', 05:23:24 INFO - 'sessionrestore_no_auto_restore', 05:23:24 INFO - 'tabpaint']}, 05:23:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['a11yr', 05:23:24 INFO - 'ts_paint', 05:23:24 INFO - 'tpaint', 05:23:24 INFO - 'sessionrestore', 05:23:24 INFO - 'sessionrestore_no_auto_restore', 05:23:24 INFO - 'tabpaint']}, 05:23:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['a11yr', 05:23:24 INFO - 'ts_paint', 05:23:24 INFO - 'tpaint', 05:23:24 INFO - 'sessionrestore', 05:23:24 INFO - 'sessionrestore_no_auto_restore', 05:23:24 INFO - 'tabpaint']}, 05:23:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['tsvgx', 05:23:24 INFO - 'tsvgr_opacity', 05:23:24 INFO - 'tart', 05:23:24 INFO - 'tscrollx', 05:23:24 INFO - 'cart']}, 05:23:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:24 INFO - 'tsvgr_opacity', 05:23:24 INFO - 'tart', 05:23:24 INFO - 'tscrollx', 05:23:24 INFO - 'cart']}, 05:23:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'talos_options': ['--disable-e10s'], 05:23:24 INFO - 'tests': ['tp5o']}, 05:23:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'tests': ['tp5o']}, 05:23:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'talos_options': ['--disable-e10s', 05:23:24 INFO - '--xperf_path', 05:23:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:24 INFO - 'tests': ['tp5n']}, 05:23:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:24 INFO - 'talos_options': ['--xperf_path', 05:23:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:24 INFO - 'tests': ['tp5n']}}, 05:23:24 INFO - 'talos.zip': {'path': '', 05:23:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:24 INFO - Running post-action listener: _resource_record_post_action 05:23:24 INFO - ##### 05:23:24 INFO - ##### Finished populate-webroot step (success) 05:23:24 INFO - ##### 05:23:24 INFO - ##### 05:23:24 INFO - ##### Running create-virtualenv step. 05:23:24 INFO - ##### 05:23:24 INFO - Running pre-action listener: _resource_record_pre_action 05:23:24 INFO - Running main action method: create_virtualenv 05:23:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:24 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 05:23:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:24 INFO - Using real prefix '/usr' 05:23:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:26 INFO - Installing pip.................done. 05:23:26 INFO - Return code: 0 05:23:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:23:26 INFO - Using env: {'DISPLAY': ':0', 05:23:26 INFO - 'HOME': '/home/cltbld', 05:23:26 INFO - 'LANG': 'en_US.UTF-8', 05:23:26 INFO - 'LANGUAGE': 'en_US:en', 05:23:26 INFO - 'LOGNAME': 'cltbld', 05:23:26 INFO - 'MAIL': '/var/mail/cltbld', 05:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:26 INFO - 'MOZ_NO_REMOTE': '1', 05:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:26 INFO - 'NO_EM_RESTART': '1', 05:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:26 INFO - 'PWD': '/builds/slave/test', 05:23:26 INFO - 'SHELL': '/bin/bash', 05:23:26 INFO - 'SHLVL': '1', 05:23:26 INFO - 'TERM': 'linux', 05:23:26 INFO - 'TMOUT': '86400', 05:23:26 INFO - 'USER': 'cltbld', 05:23:26 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:26 INFO - '_': '/tools/buildbot/bin/python'} 05:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:26 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 05:23:26 INFO - Cleaning up... 05:23:26 INFO - Return code: 0 05:23:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:23:26 INFO - Using env: {'DISPLAY': ':0', 05:23:26 INFO - 'HOME': '/home/cltbld', 05:23:26 INFO - 'LANG': 'en_US.UTF-8', 05:23:26 INFO - 'LANGUAGE': 'en_US:en', 05:23:26 INFO - 'LOGNAME': 'cltbld', 05:23:26 INFO - 'MAIL': '/var/mail/cltbld', 05:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:26 INFO - 'MOZ_NO_REMOTE': '1', 05:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:26 INFO - 'NO_EM_RESTART': '1', 05:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:26 INFO - 'PWD': '/builds/slave/test', 05:23:26 INFO - 'SHELL': '/bin/bash', 05:23:26 INFO - 'SHLVL': '1', 05:23:26 INFO - 'TERM': 'linux', 05:23:26 INFO - 'TMOUT': '86400', 05:23:26 INFO - 'USER': 'cltbld', 05:23:26 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:26 INFO - '_': '/tools/buildbot/bin/python'} 05:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:27 INFO - Downloading/unpacking psutil>=3.1.1 05:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:29 INFO - Installing collected packages: psutil 05:23:29 INFO - Running setup.py install for psutil 05:23:29 INFO - building 'psutil._psutil_linux' extension 05:23:29 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 05:23:29 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 05:23:29 INFO - building 'psutil._psutil_posix' extension 05:23:29 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 05:23:29 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 05:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:29 INFO - Successfully installed psutil 05:23:29 INFO - Cleaning up... 05:23:29 INFO - Return code: 0 05:23:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:23:29 INFO - Using env: {'DISPLAY': ':0', 05:23:29 INFO - 'HOME': '/home/cltbld', 05:23:29 INFO - 'LANG': 'en_US.UTF-8', 05:23:29 INFO - 'LANGUAGE': 'en_US:en', 05:23:29 INFO - 'LOGNAME': 'cltbld', 05:23:29 INFO - 'MAIL': '/var/mail/cltbld', 05:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:29 INFO - 'MOZ_NO_REMOTE': '1', 05:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:29 INFO - 'NO_EM_RESTART': '1', 05:23:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:29 INFO - 'PWD': '/builds/slave/test', 05:23:29 INFO - 'SHELL': '/bin/bash', 05:23:29 INFO - 'SHLVL': '1', 05:23:29 INFO - 'TERM': 'linux', 05:23:29 INFO - 'TMOUT': '86400', 05:23:29 INFO - 'USER': 'cltbld', 05:23:29 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:29 INFO - '_': '/tools/buildbot/bin/python'} 05:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:31 INFO - Installing collected packages: mozsystemmonitor 05:23:31 INFO - Running setup.py install for mozsystemmonitor 05:23:31 INFO - Successfully installed mozsystemmonitor 05:23:31 INFO - Cleaning up... 05:23:32 INFO - Return code: 0 05:23:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:23:32 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')]} 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:32 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')]} 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:32 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:32 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 05:23:32 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 05:23:32 INFO - Using env: {'DISPLAY': ':0', 05:23:32 INFO - 'HOME': '/home/cltbld', 05:23:32 INFO - 'LANG': 'en_US.UTF-8', 05:23:32 INFO - 'LANGUAGE': 'en_US:en', 05:23:32 INFO - 'LOGNAME': 'cltbld', 05:23:32 INFO - 'MAIL': '/var/mail/cltbld', 05:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:32 INFO - 'MOZ_NO_REMOTE': '1', 05:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:32 INFO - 'NO_EM_RESTART': '1', 05:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:32 INFO - 'PWD': '/builds/slave/test', 05:23:32 INFO - 'SHELL': '/bin/bash', 05:23:32 INFO - 'SHLVL': '1', 05:23:32 INFO - 'TERM': 'linux', 05:23:32 INFO - 'TMOUT': '86400', 05:23:32 INFO - 'USER': 'cltbld', 05:23:32 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:32 INFO - '_': '/tools/buildbot/bin/python'} 05:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:32 INFO - Downloading/unpacking jsonschema==2.5.1 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:34 INFO - warning: no files found matching '*.txt' 05:23:34 INFO - no previously-included directories found matching 'build' 05:23:34 INFO - no previously-included directories found matching 'dist' 05:23:34 INFO - no previously-included directories found matching '.git*' 05:23:34 INFO - Installing collected packages: jsonschema, functools32 05:23:34 INFO - Running setup.py install for functools32 05:23:34 INFO - warning: no files found matching '*.txt' 05:23:34 INFO - no previously-included directories found matching 'build' 05:23:34 INFO - no previously-included directories found matching 'dist' 05:23:34 INFO - no previously-included directories found matching '.git*' 05:23:34 INFO - Successfully installed jsonschema functools32 05:23:34 INFO - Cleaning up... 05:23:34 INFO - Return code: 0 05:23:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:34 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')]} 05:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:34 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')]} 05:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:34 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:34 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 05:23:34 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 05:23:34 INFO - Using env: {'DISPLAY': ':0', 05:23:34 INFO - 'HOME': '/home/cltbld', 05:23:34 INFO - 'LANG': 'en_US.UTF-8', 05:23:34 INFO - 'LANGUAGE': 'en_US:en', 05:23:34 INFO - 'LOGNAME': 'cltbld', 05:23:34 INFO - 'MAIL': '/var/mail/cltbld', 05:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:34 INFO - 'MOZ_NO_REMOTE': '1', 05:23:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:34 INFO - 'NO_EM_RESTART': '1', 05:23:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:34 INFO - 'PWD': '/builds/slave/test', 05:23:34 INFO - 'SHELL': '/bin/bash', 05:23:34 INFO - 'SHLVL': '1', 05:23:34 INFO - 'TERM': 'linux', 05:23:34 INFO - 'TMOUT': '86400', 05:23:34 INFO - 'USER': 'cltbld', 05:23:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:34 INFO - '_': '/tools/buildbot/bin/python'} 05:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 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 05:23:34 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 05:23:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:37 INFO - Downloading docopt-0.6.1.tar.gz 05:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:37 INFO - Running setup.py install for blobuploader 05:23:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:37 INFO - Running setup.py install for requests 05:23:37 INFO - Running setup.py install for docopt 05:23:37 INFO - Successfully installed blobuploader requests docopt 05:23:37 INFO - Cleaning up... 05:23:38 INFO - Return code: 0 05:23:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:38 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:38 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 05:23:38 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 05:23:38 INFO - Using env: {'DISPLAY': ':0', 05:23:38 INFO - 'HOME': '/home/cltbld', 05:23:38 INFO - 'LANG': 'en_US.UTF-8', 05:23:38 INFO - 'LANGUAGE': 'en_US:en', 05:23:38 INFO - 'LOGNAME': 'cltbld', 05:23:38 INFO - 'MAIL': '/var/mail/cltbld', 05:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:38 INFO - 'MOZ_NO_REMOTE': '1', 05:23:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:38 INFO - 'NO_EM_RESTART': '1', 05:23:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:38 INFO - 'PWD': '/builds/slave/test', 05:23:38 INFO - 'SHELL': '/bin/bash', 05:23:38 INFO - 'SHLVL': '1', 05:23:38 INFO - 'TERM': 'linux', 05:23:38 INFO - 'TMOUT': '86400', 05:23:38 INFO - 'USER': 'cltbld', 05:23:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:38 INFO - '_': '/tools/buildbot/bin/python'} 05:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:38 INFO - Running setup.py (path:/tmp/pip-5dTPSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:38 INFO - Running setup.py (path:/tmp/pip-KLeCT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:38 INFO - Running setup.py (path:/tmp/pip-jIatmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:38 INFO - Running setup.py (path:/tmp/pip-YjZbli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:38 INFO - Running setup.py (path:/tmp/pip-xnYsdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:38 INFO - Running setup.py (path:/tmp/pip-sPBFx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:38 INFO - Running setup.py (path:/tmp/pip-DiYPOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:38 INFO - Running setup.py (path:/tmp/pip-QHN9Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:38 INFO - Running setup.py (path:/tmp/pip-gPl7e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:38 INFO - Running setup.py (path:/tmp/pip-b0YZRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:38 INFO - Running setup.py (path:/tmp/pip-PaCYcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:39 INFO - Running setup.py (path:/tmp/pip-xiWjZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:39 INFO - Running setup.py (path:/tmp/pip-5mHgX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:39 INFO - Running setup.py (path:/tmp/pip-aA47lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:39 INFO - Running setup.py (path:/tmp/pip-A2vSjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:39 INFO - Running setup.py (path:/tmp/pip-m3Nbc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:39 INFO - Running setup.py (path:/tmp/pip-yp2Zjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:39 INFO - Running setup.py install for manifestparser 05:23:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Running setup.py install for mozcrash 05:23:39 INFO - Running setup.py install for mozdebug 05:23:39 INFO - Running setup.py install for mozdevice 05:23:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Running setup.py install for mozfile 05:23:40 INFO - Running setup.py install for mozhttpd 05:23:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for mozinfo 05:23:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for mozInstall 05:23:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for mozleak 05:23:40 INFO - Running setup.py install for mozlog 05:23:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for moznetwork 05:23:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for mozprocess 05:23:40 INFO - Running setup.py install for mozprofile 05:23:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for mozrunner 05:23:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:40 INFO - Running setup.py install for mozscreenshot 05:23:41 INFO - Running setup.py install for moztest 05:23:41 INFO - Running setup.py install for mozversion 05:23:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:41 INFO - Cleaning up... 05:23:41 INFO - Return code: 0 05:23:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:41 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:41 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 05:23:41 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 05:23:41 INFO - Using env: {'DISPLAY': ':0', 05:23:41 INFO - 'HOME': '/home/cltbld', 05:23:41 INFO - 'LANG': 'en_US.UTF-8', 05:23:41 INFO - 'LANGUAGE': 'en_US:en', 05:23:41 INFO - 'LOGNAME': 'cltbld', 05:23:41 INFO - 'MAIL': '/var/mail/cltbld', 05:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:41 INFO - 'MOZ_NO_REMOTE': '1', 05:23:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:41 INFO - 'NO_EM_RESTART': '1', 05:23:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:41 INFO - 'PWD': '/builds/slave/test', 05:23:41 INFO - 'SHELL': '/bin/bash', 05:23:41 INFO - 'SHLVL': '1', 05:23:41 INFO - 'TERM': 'linux', 05:23:41 INFO - 'TMOUT': '86400', 05:23:41 INFO - 'USER': 'cltbld', 05:23:41 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:41 INFO - '_': '/tools/buildbot/bin/python'} 05:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:41 INFO - Running setup.py (path:/tmp/pip-qvXxgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:41 INFO - Running setup.py (path:/tmp/pip-8Gm32j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:41 INFO - Running setup.py (path:/tmp/pip-85p4O3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:41 INFO - Running setup.py (path:/tmp/pip-jJnLRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:41 INFO - Running setup.py (path:/tmp/pip-jPnYX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:41 INFO - Running setup.py (path:/tmp/pip-n8jpq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:41 INFO - Running setup.py (path:/tmp/pip-akjFRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:41 INFO - Running setup.py (path:/tmp/pip-VNsPRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:42 INFO - Running setup.py (path:/tmp/pip-oVBspG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:42 INFO - Running setup.py (path:/tmp/pip-wevS0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:42 INFO - Running setup.py (path:/tmp/pip-J05v4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:42 INFO - Running setup.py (path:/tmp/pip-sPTQkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:42 INFO - Running setup.py (path:/tmp/pip-oR04i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:42 INFO - Running setup.py (path:/tmp/pip-ZHSE5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:42 INFO - Running setup.py (path:/tmp/pip-VKBb9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:42 INFO - Running setup.py (path:/tmp/pip-miHr4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:42 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)) 05:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:42 INFO - Running setup.py (path:/tmp/pip-dMDc2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:42 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)) 05:23:42 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)) 05:23:42 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)) 05:23:42 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)) 05:23:42 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)) 05:23:42 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)) 05:23:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:42 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 05:23:42 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 05:23:42 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 05:23:42 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 05:23:42 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 05:23:42 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 05:23:44 INFO - Downloading blessings-1.6.tar.gz 05:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:44 INFO - Installing collected packages: blessings 05:23:44 INFO - Running setup.py install for blessings 05:23:44 INFO - Successfully installed blessings 05:23:44 INFO - Cleaning up... 05:23:44 INFO - Return code: 0 05:23:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:45 INFO - Reading from file tmpfile_stdout 05:23:45 INFO - Current package versions: 05:23:45 INFO - argparse == 1.2.1 05:23:45 INFO - blessings == 1.6 05:23:45 INFO - blobuploader == 1.2.4 05:23:45 INFO - docopt == 0.6.1 05:23:45 INFO - functools32 == 3.2.3-2 05:23:45 INFO - jsonschema == 2.5.1 05:23:45 INFO - manifestparser == 1.1 05:23:45 INFO - mozInstall == 1.12 05:23:45 INFO - mozcrash == 0.17 05:23:45 INFO - mozdebug == 0.1 05:23:45 INFO - mozdevice == 0.48 05:23:45 INFO - mozfile == 1.2 05:23:45 INFO - mozhttpd == 0.7 05:23:45 INFO - mozinfo == 0.9 05:23:45 INFO - mozleak == 0.1 05:23:45 INFO - mozlog == 3.2 05:23:45 INFO - moznetwork == 0.27 05:23:45 INFO - mozprocess == 0.23 05:23:45 INFO - mozprofile == 0.28 05:23:45 INFO - mozrunner == 6.12 05:23:45 INFO - mozscreenshot == 0.1 05:23:45 INFO - mozsystemmonitor == 0.3 05:23:45 INFO - moztest == 0.7 05:23:45 INFO - mozversion == 1.4 05:23:45 INFO - psutil == 3.1.1 05:23:45 INFO - requests == 1.2.3 05:23:45 INFO - wsgiref == 0.1.2 05:23:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:45 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:45 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 05:23:45 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 05:23:45 INFO - Using env: {'DISPLAY': ':0', 05:23:45 INFO - 'HOME': '/home/cltbld', 05:23:45 INFO - 'LANG': 'en_US.UTF-8', 05:23:45 INFO - 'LANGUAGE': 'en_US:en', 05:23:45 INFO - 'LOGNAME': 'cltbld', 05:23:45 INFO - 'MAIL': '/var/mail/cltbld', 05:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:45 INFO - 'MOZ_NO_REMOTE': '1', 05:23:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:45 INFO - 'NO_EM_RESTART': '1', 05:23:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:45 INFO - 'PWD': '/builds/slave/test', 05:23:45 INFO - 'SHELL': '/bin/bash', 05:23:45 INFO - 'SHLVL': '1', 05:23:45 INFO - 'TERM': 'linux', 05:23:45 INFO - 'TMOUT': '86400', 05:23:45 INFO - 'USER': 'cltbld', 05:23:45 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:45 INFO - '_': '/tools/buildbot/bin/python'} 05:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 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)) 05:23:45 INFO - Cleaning up... 05:23:45 INFO - Return code: 0 05:23:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:45 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', '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 05:23:45 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 05:23:45 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 05:23:45 INFO - Using env: {'DISPLAY': ':0', 05:23:45 INFO - 'HOME': '/home/cltbld', 05:23:45 INFO - 'LANG': 'en_US.UTF-8', 05:23:45 INFO - 'LANGUAGE': 'en_US:en', 05:23:45 INFO - 'LOGNAME': 'cltbld', 05:23:45 INFO - 'MAIL': '/var/mail/cltbld', 05:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:45 INFO - 'MOZ_NO_REMOTE': '1', 05:23:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:45 INFO - 'NO_EM_RESTART': '1', 05:23:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:45 INFO - 'PWD': '/builds/slave/test', 05:23:45 INFO - 'SHELL': '/bin/bash', 05:23:45 INFO - 'SHLVL': '1', 05:23:45 INFO - 'TERM': 'linux', 05:23:45 INFO - 'TMOUT': '86400', 05:23:45 INFO - 'USER': 'cltbld', 05:23:45 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:45 INFO - '_': '/tools/buildbot/bin/python'} 05:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:45 INFO - Cleaning up... 05:23:45 INFO - Return code: 0 05:23:45 INFO - Running post-action listener: _resource_record_post_action 05:23:45 INFO - Running post-action listener: _start_resource_monitoring 05:23:45 INFO - Starting resource monitoring. 05:23:45 INFO - ##### 05:23:45 INFO - ##### Finished create-virtualenv step (success) 05:23:45 INFO - ##### 05:23:45 INFO - ##### 05:23:45 INFO - ##### Running install step. 05:23:45 INFO - ##### 05:23:45 INFO - Running pre-action listener: _resource_record_pre_action 05:23:45 INFO - Running main action method: install 05:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:45 INFO - Reading from file tmpfile_stdout 05:23:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:45 INFO - Reading from file tmpfile_stdout 05:23:45 INFO - Output received: 05:23:45 INFO - Usage: mozinstall [options] installer 05:23:45 INFO - Options: 05:23:45 INFO - -h, --help show this help message and exit 05:23:45 INFO - -d DEST, --destination=DEST 05:23:45 INFO - Directory to install application into. [default: 05:23:45 INFO - "/builds/slave/test"] 05:23:45 INFO - --app=APP Application being installed. [default: firefox] 05:23:45 INFO - mkdir: /builds/slave/test/build/application 05:23:45 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'] 05:23:45 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 05:24:02 INFO - Reading from file tmpfile_stdout 05:24:02 INFO - Output received: 05:24:02 INFO - /builds/slave/test/build/application/firefox/firefox 05:24:02 INFO - Running post-action listener: _resource_record_post_action 05:24:02 INFO - ##### 05:24:02 INFO - ##### Finished install step (success) 05:24:02 INFO - ##### 05:24:02 INFO - ##### 05:24:02 INFO - ##### Running run-tests step. 05:24:02 INFO - ##### 05:24:02 INFO - Running pre-action listener: _resource_record_pre_action 05:24:02 INFO - Running main action method: run_tests 05:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:02 INFO - Python 2.7.3 05:24:02 INFO - Return code: 0 05:24:02 INFO - grabbing minidump binary from tooltool 05:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:02 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 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:02 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 05:24:02 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 05:24:02 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 05:24:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:03 INFO - Return code: 0 05:24:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:24:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:24:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 05:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 05:24:03 INFO - Using env: {'DISPLAY': ':0', 05:24:03 INFO - 'HOME': '/home/cltbld', 05:24:03 INFO - 'LANG': 'en_US.UTF-8', 05:24:03 INFO - 'LANGUAGE': 'en_US:en', 05:24:03 INFO - 'LOGNAME': 'cltbld', 05:24:03 INFO - 'MAIL': '/var/mail/cltbld', 05:24:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:03 INFO - 'MOZ_NO_REMOTE': '1', 05:24:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:03 INFO - 'NO_EM_RESTART': '1', 05:24:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:03 INFO - 'PWD': '/builds/slave/test', 05:24:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:03 INFO - 'SHELL': '/bin/bash', 05:24:03 INFO - 'SHLVL': '1', 05:24:03 INFO - 'TERM': 'linux', 05:24:03 INFO - 'TMOUT': '86400', 05:24:03 INFO - 'USER': 'cltbld', 05:24:03 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999', 05:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:03 INFO - '_': '/tools/buildbot/bin/python'} 05:24:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 05:24:03 INFO - mozversion application_buildid: 20160711021319 05:24:03 INFO - mozversion application_changeset: 0136b1e24160b1daf36846fb5aaf4a52e6f6d457 05:24:03 INFO - mozversion application_display_name: Nightly 05:24:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:03 INFO - mozversion application_name: Firefox 05:24:03 INFO - mozversion application_remotingname: firefox 05:24:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:24:03 INFO - mozversion application_vendor: Mozilla 05:24:03 INFO - mozversion application_version: 50.0a1 05:24:03 INFO - mozversion platform_buildid: 20160711021319 05:24:03 INFO - mozversion platform_changeset: 0136b1e24160b1daf36846fb5aaf4a52e6f6d457 05:24:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:24:03 INFO - mozversion platform_version: 50.0a1 05:24:03 INFO - using testdate: 1468239843 05:24:03 INFO - actual date: 1468239843 05:24:03 INFO - starting webserver on 'localhost:44557' 05:24:03 INFO - SUITE-START | Running 2 tests 05:24:03 INFO - TEST-START | dromaeo_css 05:24:03 INFO - operating with platform_type : linux_ 05:24:03 INFO - Initialising browser for dromaeo_css test... 05:24:03 INFO - TEST-INFO | started process 7139 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptQftWL/profile http://localhost:44557/getInfo.html) 05:24:07 INFO - PROCESS | 7139 | __metrics Screen width/height:1600/1200 05:24:07 INFO - PROCESS | 7139 | colorDepth:24 05:24:07 INFO - PROCESS | 7139 | Browser inner width/height: 1024/697 05:24:07 INFO - PROCESS | 7139 | __metrics 05:24:08 INFO - PROCESS | 7139 | 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" 05:24:08 INFO - PROCESS | 7139 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:09 INFO - TEST-INFO | 7139: exit 0 05:24:09 INFO - Browser initialized. 05:24:09 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:09 INFO - TEST-INFO | started process 7220 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptQftWL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:20 INFO - PROCESS | 7220 | RSS: Main: 157675520 05:24:20 INFO - PROCESS | 7220 | 05:25:12 INFO - PROCESS | 7220 | 1468239912359 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] 05:27:20 INFO - PROCESS | 7220 | Cycle 1(1): loaded http://localhost:44557/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44557/tests/dromaeo/cssquery-ext.html) 05:27:21 INFO - PROCESS | 7220 | RSS: Main: 258891776 05:27:21 INFO - PROCESS | 7220 | 05:30:23 INFO - PROCESS | 7220 | Cycle 1(1): loaded http://localhost:44557/tests/dromaeo/cssquery-ext.html (next: http://localhost:44557/tests/dromaeo/cssquery-jquery.html) 05:30:24 INFO - PROCESS | 7220 | RSS: Main: 243101696 05:30:24 INFO - PROCESS | 7220 | 05:33:26 INFO - PROCESS | 7220 | Cycle 1(1): loaded http://localhost:44557/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44557/tests/dromaeo/cssquery-mootools.html) 05:33:26 INFO - PROCESS | 7220 | RSS: Main: 243687424 05:33:26 INFO - PROCESS | 7220 | 05:36:28 INFO - PROCESS | 7220 | Cycle 1(1): loaded http://localhost:44557/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44557/tests/dromaeo/cssquery-prototype.html) 05:36:29 INFO - PROCESS | 7220 | RSS: Main: 233705472 05:36:29 INFO - PROCESS | 7220 | 05:39:32 INFO - PROCESS | 7220 | Cycle 1(1): loaded http://localhost:44557/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44557/tests/dromaeo/cssquery-yui.html) 05:39:32 INFO - PROCESS | 7220 | RSS: Main: 224206848 05:39:32 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | Cycle 1(1): loaded http://localhost:44557/tests/dromaeo/cssquery-yui.html (next: http://localhost:44557/tests/dromaeo/cssquery-dojo.html) 05:42:35 INFO - PROCESS | 7220 | RSS: Main: 281821184 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | __start_tp_report 05:42:35 INFO - PROCESS | 7220 | _x_x_mozilla_page_load 05:42:35 INFO - PROCESS | 7220 | _x_x_mozilla_page_load_details 05:42:35 INFO - PROCESS | 7220 | |i|pagename|runs| 05:42:35 INFO - PROCESS | 7220 | |0;dojo.html;3010.6387233531973;3047.055412822027;3057.6789437109105;3072.969270307297;3074.8616312265945;5177.352830896138;5301.019311427389;5354.330708661418;5446.346438427389;5464.262324586185;5248.685078895269;5332.226827110067;5361.490658387456;5374.086405311096;5387.218853266274;4845.127876982142;4847.054824309261;4926.926096108561;4942.901141977159;5043.5965122790085;4946.431160416544;4948.901021979558;4963.053818769252;4999.3000979862845;5000.624953128512;4756.262779269202;4825.589824864891;4834.637402194814;4862.6109911207195;4890.902181956341;4810.7835147418455;4828.203346447832;4906.901861962777;4917.4344950330815;4959.305697202394;4783.880402989918;4870.342503762017;4876.049170411771;4888.853334399973;4919.26211068339;5291.047611429941;5374.731263436813;5374.973125134349;5418.241446197536;5478.424765399615;5809.6804675742815;5833.883322333572;5849.327327357366;5855.795047173371;5859.033259512175;5627.296587926509;5751.166080918285;5849.736761845685;5911.231539899829;5916.645001299893;5458.645188062763;5488.286522752057;5490.972545137329;5491.176323551499;5494.642848214813;5956.7319470623925;5965.28416590012;5995.160677505153;5996.220491336098;5996.490298324604;5548.667079975215;5641.576881733887;5660.264165658438;5724.771009159587;5740.54075673945;5812.651240925558;5814.069748840166;5856.765729370863;5866.560007999417;5872.500837428781;5726.942730572641;5737.282839645044;5785.942140578624;5794.50746686528;5840.970795145997;5515.669059856422;5647.124695672177;5686.60193786431;5704.914426283609;5768.538516918637;4393.890152746207;4402.427684401007;4406.713563618355;4407.096545208225;4438.822447102079;3903.2583809076186;3918.921621567553;3920.5099362579676;3962.980185099114;3973.4039894016128;5277.525022747971;5336.439673834191;5369.677819330853;5391.191321301836;5407.378151512627;5743.971280143656;5755.597108202385;5769.653820770767;5770.826875193751;5788.27646544182;5344.412114667383;5473.31583552056;5478.123500239943;5480.369757477862;5480.835574932758;5016.473270306638;5051.797928082909;5076.238564215398;5095.97452012745;5100.030994111107;6102.267727872683;6102.603330783573;6109.786157484556;6119.357467465941;6124.5100391968535;5608.242887210337;5612.7754889803955;5864.44287792659;5887.028640274478;5888.205092312654;5826.096954971986;5933.406659334032;5940.79207227745;5943.821685349447;5944.3164036136395;5535.944640553543;5587.944120558743;5588.022096133242;5590.692511911884;5608.214849921096;5608.8878222434505;5659.236003139524;5666.603337766317;5706.714664266687;5707.8858422832145;5586.776528938797;5590.2453168821685;5593.524550413098;5632.971835140962;5638.097904335286;5501.669899806025;5505.889882202414;5533.86555756061;5551.472610102034;5553.9725150847225;5375.865603359791;5520.254765606592;5567.331920169606;5581.162825576196;5595.692236927008;5728.79949201776;5731.799387021434;5775.711214439345;5781.537477001746;5786.855328616818;5580.525655319181;5618.2415373924;5624.690642014565;5625.156226566048;5629.240052592848 05:42:35 INFO - PROCESS | 7220 | |1;ext.html;594.5808205215321;614.7110857896788;619.2074145094281;632.09610257332;637.7417146055848;35338.116547086305;35911.640883591186;36005.099872503204;42507.36238956418;43387.13225735483;44815.77592112043;45383;45406;45426;45483.77258113705;8904.599293031797;9105.772355691095;9114.13415725505;9118.954405227965;9132;8800.559972001374;8818.911810881906;8903.24322432594;8906.153915378061;8910.42082264654;8966.193042626162;8992.370534062618;9000.77498062547;9001.189892909637;9002.32482563807;8904.287656987457;8956;8966.462012279284;8969.820603587925;8971.192592666692;8873.11268873114;8934.06192349807;8937.6871809487;8940.46357218569;8947.50788706621;43364.78317608392;43878.78060609676;43910.78044609789;43935.78032109851;44037;3706.5181526401666;3741.5884252732176;3807.505024346817;3838.8848334550007;3870.7290489665734;32507.674923250703;32645.836770816233;33223.16942076467;33866.83066584676;34272.657273427445;4349.80425880833;4362.738235705868;4366.432363792718;4421.137878113769;4515.14212299662;363196;364669;364813;366374;367797;34351.82824085913;35263.294734105686;35291.823540882135;58201.417985820444;58650.70674646599;37199.62800372016;37218.62781372206;37289;37313.81343093267;37353;15655.608609784618;15756.448524301824;15785.052896826228;15787.921060394625;15792.289346979414;13282.800757988638;13345.132566383349;13374.732505349839;13384.93307533456;13392.397342119628;8459.873101903353;8465.111163327885;8481.194286542768;8522.74431767048;8539.316854651614;3255.348930213919;3264.7224985876464;3272.1001724525468;3294.341131773655;3335.0661814691925;957.5212393803098;982.9311948163703;1038.309524166436;1093.9206134730707;1149.270213414488;32509.51235731466;32761.836190818893;33306.66693333036;34183.11992840368;34396.48405273923;1083.983740243897;1095.4030053620795;1171.4552732979205;1176.6822957801346;1178.6287319494331;16978.23597938096;17032.65934681324;17143.485695429157;17144.485665430057;17256.913715431594;568493;573031;579252;579792;581525;57398;58043.419565804645;58145.12782308269;58227.70886145542;58280.708596456745;1260.7163388237757;1333.093496422442;1381.951631692886;1388.3058470764618;1409.9506517271845;31834.522482162323;33687;34066.318673625894;34450.48324275188;34497.827510862284;50964.23553646773;51318.48681513286;51338.48661513337;51725;51757.482425175265;432654;435623;435670;435917;436245;24298;26734;27254.863725681244;27646.44707105887;27773;46627;47044.52955470538;47181.29228061513;47726.04547909092;48013.5198648023;40809;40815.7959210202;40914;41398.58601413947;42443.36334954917;40106.39840402455;40899;41378;41668.583314167685;42425;40328.79835600803;40690.59309406987;40895.1820963585;41069.38395924123;41308;34573.827130864185;35772.414446111055;35842.31525040137;36380.81809590935;36621.26757464889;27121.86439067792;27577.724222757515;27617.171484855488;27719.445611087263;27805.582916256677 05:42:35 INFO - PROCESS | 7220 | |2;jquery.html;398.32881594513236;398.67507980995504;403.23788040603245;407.56797794338;418.988143633126;13745.65635859104;18007.81992180078;19570.804291957094;19617.21531138753;19979.800201997976;15057.322420491077;19434;19457.41627751166;20396.796032039674;20794;5743;5878.470937615613;5914.792982245623;5923.111533270023;5949.613275137102;4942.777575009115;5789.45000224978;5915.792947246847;5915.9704201478935;5923.822285331447;4246.2356775780345;6268.686565671698;6545.214574251097;6549.345065493437;6601.702923368436;5631.324241091054;5941.584089113764;6584.604923704569;6628.900566491507;6637.800865974028;5720.742566584472;5772.076467765138;5804.796832110898;5820.243368362128;5828.329742079631;14336.56990290293;15331.080135191813;21907.561848763096;22876.65685014726;23109.653355199687;2119.5337025854287;2197.6923230747548;2738.9178324650093;2984.2986898078934;3016.7737419693613;11051.834222486668;11778.823317650242;13905.16569005863;14849.406023759037;15437.536873893801;1964.7052942058663;1982.7719812221635;1996.3212507747435;2002.1590931808676;2008.5179556906382;77818.6109069451;84530;84800.57599712024;85138.57430712869;85497.74027116723;10957.83563246552;11097.500612472459;11215.775684486265;11235.61810893752;11842.230255033395;15543.067415955078;15563.610909727347;15644.608884777972;15680.294386752623;15692.33230350843;8269.131741167092;8344.207300306462;8356.540390278593;8430.747077587683;8504.957475212585;7895.802604934807;7919.554993577812;8016.39877009215;8100.432969692065;8164.836703265902;4479.3057076153245;4831.444383895827;4848.248521479177;4924.532169443897;8335.624621937312;3183.1564635371647;3340.8162551059927;3377.6453472385538;3386.847591858372;3562.590302115238;915.4415806358116;976.1214906583989;976.7313988653177;1023.3655133816932;1036.3056751976194;19847.404577862686;20752.896235518725;20760.896195518926;20809.687854682194;20945.476363091046;997.6358629100471;1011.5903059261011;1014.2190513304862;1019.928604997643;1051.4164638625484;10918.563257469612;11378.089752819755;11453.828192577119;11461.54153833854;11487.483063262172;450764;452177;452179;454618;469501;92841;96965;97214;97336.51331743295;99020.5048974765;952.2810278239884;970.8301047316692;995.233670074039;1000.2698030437821;1001.0490034467285;17786.288548457916;17875.821241787937;18204;18207.635847282716;18607.720884186478;44897.32654010127;45849.312260315455;46052.7697361511;47256.7637161812;47462.288065679735;63468.682656586425;63852.68073659602;64585.677071614344;65628.67185664232;66546;8932.598033088396;9121.589528471102;9229.630814767603;9299.116583924517;9413.670521531714;13389.330533473481;13986.230757308445;14114.858851411354;14362.353694083582;14617.196054216694;11978.401079945794;12064.93967530192;12097.57658481949;12105.092118091003;12278;12202.14584979043;12250.938745306217;12321.815172772236;12368.505259789508;12447.394702453974;11738.354390508604;12732.936335318635;13038.608841734764;13099.607011789662;13111.672208194872;8770.47377157347;8770.618033444287;8843.955780221058;8906.777330566787;9326.347155699035;9161.312901532545;11898;12306.323152226714;12444.128910976508;12712.427940742504 05:42:35 INFO - PROCESS | 7220 | |3;mootools.html;1350.716349566591;1459.7591397419426;1516.052467207995;1615.6849414364203;1756.771619689441;8068.31419329357;8307.335413166948;8339.24946754792;8592.226699597035;8601.569921503931;8401.621927013284;8615.741527754177;8619.612117454713;8634.136586341363;8636.093210212915;1982.2467462364282;2287.9885600572056;2338.1582630253097;2359.5162991586685;2372.525494901018;2360.2919124262653;2377.2749311459997;2378.15575470708;2378.8810559472045;2381.4403615150513;2342.590046741822;2357.1985524921524;2368.46709490365;2371.34787933318;2373.9169129080487;1948.7758907725581;2172.0768673313937;2239.708837851069;2244.046280330869;2262;2290.530441259538;2300.4478925058033;2317.895694693743;2318.466752646884;2319.095552734435;8933;9051.04963978786;9052.049534798884;9062.682806101755;9074.818503629956;5181.5854731621375;5317.308749862532;5319.388270348921;5321.228421878806;5322.414534401251;3374.645662205482;3414.5048967899766;3481.3385456763135;3510.438329867209;3569.821508924543;1099.934003959765;1115.4868760370239;1120.5965852293168;1121.6522877907876;1126.5268587193398;12625.684357891034;12630.557930472482;12631.242125472501;12641.87358126416;12650.304233267074;4639.976800115978;4696.412948381452;4749.371464487719;4762.690425122356;4883.365162528919;10489;10490.370577765358;10491.790164196826;10500.474976251156;10518.73703157412;5375.4624537546715;5376.542993845567;5378.408375078739;5382;5393.7033463159905;4153;4797.4482934462985;4931.876703082452;4955.950440495621;4960.503949605083;4006.25884211416;4052;4107.856225032169;4161.771102589387;4215.451991241181;1122.259308856151;1292.954746583865;1306.6145487081144;1308.535469908188;1325.6221976736583;902.9909700902907;916.6058594804165;964.2767924056957;967.2117224462041;1007.969760907174;5738.426157384228;6044.576879618472;6059.575829691968;6133.233345831771;6151.938480615226;949.482532020037;977.6920270114891;989.5200827598609;997.7605374710163;1008.9495525223709;3994.4407782910416;4031.8387264509092;4031.959680403217;4070.9796451017555;4081.285774989424;10269;10624.096951759188;10720.785584288271;10725.678229653124;10731.356118632906;10614.840777388345;10622.628208012682;10631.627893023708;10643;10645.467726613793;8033.5983200838555;8067.39494537912;8136.389770767216;8147.877781833277;8157.3474122071275;5726.341470730919;5796.449337312949;5824.737886795019;5857.941420585739;5930.436608522184;9940.950295248478;10033.397996120257;10034.397936123856;10077.496125193564;10103;10344.37933723979;10538.315009524356;10550.788984220426;10634.202434817575;10661.360318380923;7285.235050319852;8219.136990616138;8525;8567.61445734931;8755.080716524928;8854.690085847222;8928;8951.552422378725;8999.055099214751;9004.054574269607;7425.554466732014;7500.287472690085;7592.544447333178;7628.0083589133055;7874.251946065115;7622.961885190539;7700.807479813049;7807.960960195162;7809.062912450542;7846.215378462336;7185.353318201387;7218.494705370784;7250.166230883516;7254.056972593529;7256.600886951268;4872.731999740048;5290.100682883842;5291.656042357235;5369.167778994262;5455.945440545703;5295.7087360195555;5311.893762124813;5359.517643412111;5446.727663616724;5448 05:42:35 INFO - PROCESS | 7220 | |4;prototype.html;326.2217632382818;339.78933061501874;345.73896707985466;347.0525465479243;349.655589244594;5146.794128234867;5241;5300.708461034642;5357.49103835136;5396;5242.012053129708;5278.419373868881;5356.38401583818;5364.865878353043;5387.488188622085;4206.179794939981;4217.683673724468;4220.345846393814;4221.113566151096;4227.408162857202;4015.6988225883174;4060.8375664973514;4125.504939407276;4229.090745489719;4229.175310814392;4159.355299928516;4185.100203456272;4186.3092589722655;4205.612147991155;4212.862527117676;4173.1862286854075;4175.269327867611;4198.160367926412;4206.432131662217;4206.936895946563;3611.6388361163936;3692.833822477995;3738.7943663098517;3741.27045226181;3759.9624003759886;5119.820806271762;5130.179171332569;5133.51231632998;5140.280360749498;5194.532492075694;4720.457147428056;4749.715017098985;4776.808927642925;4777.80888764449;4782.450018247911;5293.4971177738435;5437.009644695402;5465.289512363407;5498.560115190815;5518.944810551883;3940.9950462631846;3943.802809859524;3947.072437977073;3951.8221680024467;3968.7420317679257;5578.577462747014;5636.182753500761;5707.942920570783;5724.227229324071;5753.194552762575;3959.3071212538266;4022.939655905164;4025.5243025539085;4025.9597404025585;4034.515858097047;4067.6745860331694;4083.448734420815;4095;4095.5904409559403;4096.610821971967;4645.605123564467;4652.697574657636;4663.790129444184;4670.462896766915;4671.95328046722;5278.709670968057;5457.335512668595;5490.6980116094;5492.5331346835965;5555.666660000413;1359.7008658095203;1388.1532265318149;1403.4316101978716;1408.6689627937426;1410.168000879486;954.6610953111546;974.2936371130874;984.1831280037552;988.847992089208;992.0575453319375;5260.605454590921;5279.577633789288;5311;5332.9466705333225;5340.732963351817;5519.199716041152;5527.198556209327;5538.889222215533;5543.889122217533;5552.555795536347;5127.64106512548;5236.114998600501;5289.920651190235;5312.442193569696;5340.2256672783005;2637.95558442205;2684.4228490874557;2685.9462810743676;2687.462507498469;2688.3735340119338;5146.614003949719;5150.227465880148;5173.4826517347965;5186.936677981005;5248.711320877388;4863.319135320987;5087.9061001884775;5101.566366858784;5109.97800439906;5112.182050871917;5144.73953881294;5168.276441298296;5182.274481572658;5190;5205.9739701302005;4995.450500444876;5033.892543640393;5037.891663833951;5042.26042015504;5047.570956468594;5031.572316353005;5226.712530810842;5720.656760594377;5733.168690539932;5758.827235182951;6012.849678758066;6032.547558933185;6041.918621502978;6046.697665116639;6047.455728984412;4003.279409706281;4084.142330110591;4091.6522095622204;4102.364133559303;4103.876883693494;5380.946190538201;5553.9175757455005;5728.112142617901;5734.627249228787;5785.710714464176;5530.695811730233;5540.529055030368;5558.805441809679;5572.359178694502;5615.034737841723;5586.608937374344;5608.775648973995;5610.438956104357;5626.915596266142;5627.8593035174445;5274.547335488863;5495.845872366848;5560.638558493685;5568.721563921868;5586.86032849182;5584.776608935597;5592.524635405874;5639.88720225585;5643.689597072036;5667.574931880043;5170.560502357342;5503.2020357048805;5506.614536982373;5514.779408823762;5539.8892022160135 05:42:35 INFO - PROCESS | 7220 | |5;yui.html;736.3630459652402;757.984840303194;766.881133424319;809.7004108479863;889.3507739350276;14491.710165796678;15405.15271660061;15426.922865385672;15476.458323958665;15547.222638868068;15803.288852001659;15933.362665493396;16016.59958501038;16022.118783466905;16022.278997445113;676.1818199978007;793.1473665809249;794.1819925476753;824.686619084747;826.0871736730916;821.7699044267615;822.9053658829251;825.2366560931144;825.3108654273659;825.694493037577;862.3833958719514;867.1242045533993;868.6916144768611;869.5565261716507;869.9347548933823;713.19052874987;836.481381543444;851.1105894340429;853.0573716043806;853.4580541356283;617.9783707570214;620.5997131849977;623.1680706257152;623.5136593457065;623.5479277523805;13926.373313200931;13926.58220253394;13936.51222207228;13965.720685586231;14002.57992260234;14317.57047288583;14652.780208296885;14681;14695.926520367437;14726;12036.699082522919;12082.395880205955;12260.44827982743;13542.796858047137;14010.29948502581;4535.274356103007;4572.199865023607;4604.907901841978;4616.0767846430845;4745.549172828576;80692.59653701753;82673.58663206706;83029;83084;83821.16178838255;10082.29423940317;10137.239707022003;10192.541335639915;10204.489775511194;10374.636887708893;16724.414645487348;16821.91589042047;17131;17496.91251543734;17607.911960440117;8433.536155511383;8625.0943650917;8758.474491530402;8769.166929141722;8919.152680495343;4614.353990441341;4808.711477311373;4893.975530122327;4905.337779399807;4935.346758378986;3393;3505.1237190702323;3514.262004978932;3521.2605352876185;3532.1346270163976;538.71178919278;588.1883001458046;620.1751670278519;630.9747610095636;632.5098049012053;742.38011260598;780.2587541835165;780.8711562592222;813.5972693516629;826.669332267098;10442.947785261025;10596.841047384296;10913;11004.724881878017;11037.33775973428;753.15270320889;764.2319468933797;766.577997814047;777.2111307023375;781.9022622172229;5031.6163055159395;5088.236764485357;5111.258867464271;5151.793928242828;5169.870753231199;116141.419292903;127057;128837;130240;132325;69488.30511694818;85500;87040.56479717688;102326;110428.4478577602;78755;80442;89605.55197223971;91012.08987910036;91769;10056.491526271118;10131.392116473035;10344.793104137725;10783.245172837826;10813.891861081289;39019.41470877882;43524.56475435205;50691.74654126706;52527;53436.122026577825;43504;43551.38691678509;50450.72971716208;75082.62458687671;80511.59744201241;12508.812367814671;12526.561570344995;12985.610431687064;13228.07403481747;13415.865841341853;10845.457727113771;14090.084144530572;14495.275236238356;14984.850151498345;15254.084754914296;14658.486952956595;14700.191489468181;14807.851921481079;15012.849871501145;15671.216439177768;12551.560695375618;13122.14706044104;13275.80086298687;13312.400941957829;13657.521986730417;13039.674008149872;13718.314084295946;13894.583162505141;14054.648633784238;14329.498467554002;12249.510019599116;12252.571160009356;12263.313756967184;12276.754464910831;12381.195222310522;11844.546527894745;12027.15809893299;12186.634400967985;12260.938695306466;12397.566085186974 05:42:35 INFO - PROCESS | 7220 | __end_tp_report 05:42:35 INFO - PROCESS | 7220 | __start_cc_report 05:42:35 INFO - PROCESS | 7220 | _x_x_mozilla_cycle_collect,844 05:42:35 INFO - PROCESS | 7220 | __end_cc_report 05:42:35 INFO - PROCESS | 7220 | __startTimestamp1468240955665__endTimestamp 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | ------- Summary: start ------- 05:42:35 INFO - PROCESS | 7220 | Number of tests: 6 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | [#0] dojo.html Cycles:165 Average:5356.38 Median:5548.67 stddev:621.39 (11.2%) stddev-sans-first:595.42 05:42:35 INFO - PROCESS | 7220 | Values: 3010.6 3047.1 3057.7 3073.0 3074.9 5177.4 5301.0 5354.3 5446.3 5464.3 5248.7 5332.2 5361.5 5374.1 5387.2 4845.1 4847.1 4926.9 4942.9 5043.6 4946.4 4948.9 4963.1 4999.3 5000.6 4756.3 4825.6 4834.6 4862.6 4890.9 4810.8 4828.2 4906.9 4917.4 4959.3 4783.9 4870.3 4876.0 4888.9 4919.3 5291.0 5374.7 5375.0 5418.2 5478.4 5809.7 5833.9 5849.3 5855.8 5859.0 5627.3 5751.2 5849.7 5911.2 5916.6 5458.6 5488.3 5491.0 5491.2 5494.6 5956.7 5965.3 5995.2 5996.2 5996.5 5548.7 5641.6 5660.3 5724.8 5740.5 5812.7 5814.1 5856.8 5866.6 5872.5 5726.9 5737.3 5785.9 5794.5 5841.0 5515.7 5647.1 5686.6 5704.9 5768.5 4393.9 4402.4 4406.7 4407.1 4438.8 3903.3 3918.9 3920.5 3963.0 3973.4 5277.5 5336.4 5369.7 5391.2 5407.4 5744.0 5755.6 5769.7 5770.8 5788.3 5344.4 5473.3 5478.1 5480.4 5480.8 5016.5 5051.8 5076.2 5096.0 5100.0 6102.3 6102.6 6109.8 6119.4 6124.5 5608.2 5612.8 5864.4 5887.0 5888.2 5826.1 5933.4 5940.8 5943.8 5944.3 5535.9 5587.9 5588.0 5590.7 5608.2 5608.9 5659.2 5666.6 5706.7 5707.9 5586.8 5590.2 5593.5 5633.0 5638.1 5501.7 5505.9 5533.9 5551.5 5554.0 5375.9 5520.3 5567.3 5581.2 5595.7 5728.8 5731.8 5775.7 5781.5 5786.9 5580.5 5618.2 5624.7 5625.2 5629.2 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | [#1] ext.html Cycles:180 Average:60342.93 Median:40906.50 stddev:124373.18 (304.0%) stddev-sans-first:124641.18 05:42:35 INFO - PROCESS | 7220 | Values: 594.6 614.7 619.2 632.1 637.7 35338.1 35911.6 36005.1 42507.4 43387.1 44815.8 45383.0 45406.0 45426.0 45483.8 8904.6 9105.8 9114.1 9119.0 9132.0 8800.6 8818.9 8903.2 8906.2 8910.4 8966.2 8992.4 9000.8 9001.2 9002.3 8904.3 8956.0 8966.5 8969.8 8971.2 8873.1 8934.1 8937.7 8940.5 8947.5 43364.8 43878.8 43910.8 43935.8 44037.0 3706.5 3741.6 3807.5 3838.9 3870.7 32507.7 32645.8 33223.2 33866.8 34272.7 4349.8 4362.7 4366.4 4421.1 4515.1 363196.0 364669.0 364813.0 366374.0 367797.0 34351.8 35263.3 35291.8 58201.4 58650.7 37199.6 37218.6 37289.0 37313.8 37353.0 15655.6 15756.4 15785.1 15787.9 15792.3 13282.8 13345.1 13374.7 13384.9 13392.4 8459.9 8465.1 8481.2 8522.7 8539.3 3255.3 3264.7 3272.1 3294.3 3335.1 957.5 982.9 1038.3 1093.9 1149.3 32509.5 32761.8 33306.7 34183.1 34396.5 1084.0 1095.4 1171.5 1176.7 1178.6 16978.2 17032.7 17143.5 17144.5 17256.9 568493.0 573031.0 579252.0 579792.0 581525.0 57398.0 58043.4 58145.1 58227.7 58280.7 1260.7 1333.1 1382.0 1388.3 1410.0 31834.5 33687.0 34066.3 34450.5 34497.8 50964.2 51318.5 51338.5 51725.0 51757.5 432654.0 435623.0 435670.0 435917.0 436245.0 24298.0 26734.0 27254.9 27646.4 27773.0 46627.0 47044.5 47181.3 47726.0 48013.5 40809.0 40815.8 40914.0 41398.6 42443.4 40106.4 40899.0 41378.0 41668.6 42425.0 40328.8 40690.6 40895.2 41069.4 41308.0 34573.8 35772.4 35842.3 36380.8 36621.3 27121.9 27577.7 27617.2 27719.4 27805.6 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | [#2] jquery.html Cycles:180 Average:28843.29 Median:3474.72 stddev:75570.66 (2174.9%) stddev-sans-first:75752.47 05:42:35 INFO - PROCESS | 7220 | Values: 398.3 398.7 403.2 407.6 419.0 13745.7 18007.8 19570.8 19617.2 19979.8 15057.3 19434.0 19457.4 20396.8 20794.0 5743.0 5878.5 5914.8 5923.1 5949.6 4942.8 5789.5 5915.8 5916.0 5923.8 4246.2 6268.7 6545.2 6549.3 6601.7 5631.3 5941.6 6584.6 6628.9 6637.8 5720.7 5772.1 5804.8 5820.2 5828.3 14336.6 15331.1 21907.6 22876.7 23109.7 2119.5 2197.7 2738.9 2984.3 3016.8 11051.8 11778.8 13905.2 14849.4 15437.5 1964.7 1982.8 1996.3 2002.2 2008.5 77818.6 84530.0 84800.6 85138.6 85497.7 10957.8 11097.5 11215.8 11235.6 11842.2 15543.1 15563.6 15644.6 15680.3 15692.3 8269.1 8344.2 8356.5 8430.7 8505.0 7895.8 7919.6 8016.4 8100.4 8164.8 4479.3 4831.4 4848.2 4924.5 8335.6 3183.2 3340.8 3377.6 3386.8 3562.6 915.4 976.1 976.7 1023.4 1036.3 19847.4 20752.9 20760.9 20809.7 20945.5 997.6 1011.6 1014.2 1019.9 1051.4 10918.6 11378.1 11453.8 11461.5 11487.5 450764.0 452177.0 452179.0 454618.0 469501.0 92841.0 96965.0 97214.0 97336.5 99020.5 952.3 970.8 995.2 1000.3 1001.0 17786.3 17875.8 18204.0 18207.6 18607.7 44897.3 45849.3 46052.8 47256.8 47462.3 63468.7 63852.7 64585.7 65628.7 66546.0 8932.6 9121.6 9229.6 9299.1 9413.7 13389.3 13986.2 14114.9 14362.4 14617.2 11978.4 12064.9 12097.6 12105.1 12278.0 12202.1 12250.9 12321.8 12368.5 12447.4 11738.4 12732.9 13038.6 13099.6 13111.7 8770.5 8770.6 8844.0 8906.8 9326.3 9161.3 11898.0 12306.3 12444.1 12712.4 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | [#3] mootools.html Cycles:180 Average:5847.69 Median:4780.07 stddev:3321.24 (69.5%) stddev-sans-first:3313.36 05:42:35 INFO - PROCESS | 7220 | Values: 1350.7 1459.8 1516.1 1615.7 1756.8 8068.3 8307.3 8339.2 8592.2 8601.6 8401.6 8615.7 8619.6 8634.1 8636.1 1982.2 2288.0 2338.2 2359.5 2372.5 2360.3 2377.3 2378.2 2378.9 2381.4 2342.6 2357.2 2368.5 2371.3 2373.9 1948.8 2172.1 2239.7 2244.0 2262.0 2290.5 2300.4 2317.9 2318.5 2319.1 8933.0 9051.0 9052.0 9062.7 9074.8 5181.6 5317.3 5319.4 5321.2 5322.4 3374.6 3414.5 3481.3 3510.4 3569.8 1099.9 1115.5 1120.6 1121.7 1126.5 12625.7 12630.6 12631.2 12641.9 12650.3 4640.0 4696.4 4749.4 4762.7 4883.4 10489.0 10490.4 10491.8 10500.5 10518.7 5375.5 5376.5 5378.4 5382.0 5393.7 4153.0 4797.4 4931.9 4956.0 4960.5 4006.3 4052.0 4107.9 4161.8 4215.5 1122.3 1293.0 1306.6 1308.5 1325.6 903.0 916.6 964.3 967.2 1008.0 5738.4 6044.6 6059.6 6133.2 6151.9 949.5 977.7 989.5 997.8 1008.9 3994.4 4031.8 4032.0 4071.0 4081.3 10269.0 10624.1 10720.8 10725.7 10731.4 10614.8 10622.6 10631.6 10643.0 10645.5 8033.6 8067.4 8136.4 8147.9 8157.3 5726.3 5796.4 5824.7 5857.9 5930.4 9941.0 10033.4 10034.4 10077.5 10103.0 10344.4 10538.3 10550.8 10634.2 10661.4 7285.2 8219.1 8525.0 8567.6 8755.1 8854.7 8928.0 8951.6 8999.1 9004.1 7425.6 7500.3 7592.5 7628.0 7874.3 7623.0 7700.8 7808.0 7809.1 7846.2 7185.4 7218.5 7250.2 7254.1 7256.6 4872.7 5290.1 5291.7 5369.2 5455.9 5295.7 5311.9 5359.5 5446.7 5448.0 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | [#4] prototype.html Cycles:180 Average:4588.35 Median:5169.42 stddev:1331.84 (25.8%) stddev-sans-first:1296.58 05:42:35 INFO - PROCESS | 7220 | Values: 326.2 339.8 345.7 347.1 349.7 5146.8 5241.0 5300.7 5357.5 5396.0 5242.0 5278.4 5356.4 5364.9 5387.5 4206.2 4217.7 4220.3 4221.1 4227.4 4015.7 4060.8 4125.5 4229.1 4229.2 4159.4 4185.1 4186.3 4205.6 4212.9 4173.2 4175.3 4198.2 4206.4 4206.9 3611.6 3692.8 3738.8 3741.3 3760.0 5119.8 5130.2 5133.5 5140.3 5194.5 4720.5 4749.7 4776.8 4777.8 4782.5 5293.5 5437.0 5465.3 5498.6 5518.9 3941.0 3943.8 3947.1 3951.8 3968.7 5578.6 5636.2 5707.9 5724.2 5753.2 3959.3 4022.9 4025.5 4026.0 4034.5 4067.7 4083.4 4095.0 4095.6 4096.6 4645.6 4652.7 4663.8 4670.5 4672.0 5278.7 5457.3 5490.7 5492.5 5555.7 1359.7 1388.2 1403.4 1408.7 1410.2 954.7 974.3 984.2 988.8 992.1 5260.6 5279.6 5311.0 5332.9 5340.7 5519.2 5527.2 5538.9 5543.9 5552.6 5127.6 5236.1 5289.9 5312.4 5340.2 2638.0 2684.4 2685.9 2687.5 2688.4 5146.6 5150.2 5173.5 5186.9 5248.7 4863.3 5087.9 5101.6 5110.0 5112.2 5144.7 5168.3 5182.3 5190.0 5206.0 4995.5 5033.9 5037.9 5042.3 5047.6 5031.6 5226.7 5720.7 5733.2 5758.8 6012.8 6032.5 6041.9 6046.7 6047.5 4003.3 4084.1 4091.7 4102.4 4103.9 5380.9 5553.9 5728.1 5734.6 5785.7 5530.7 5540.5 5558.8 5572.4 5615.0 5586.6 5608.8 5610.4 5626.9 5627.9 5274.5 5495.8 5560.6 5568.7 5586.9 5584.8 5592.5 5639.9 5643.7 5667.6 5170.6 5503.2 5506.6 5514.8 5539.9 05:42:35 INFO - PROCESS | 7220 | 05:42:35 INFO - PROCESS | 7220 | [#5] yui.html Cycles:180 Average:20593.10 Median:3526.70 stddev:30257.85 (858.0%) stddev-sans-first:30306.00 05:42:35 INFO - PROCESS | 7220 | Values: 736.4 758.0 766.9 809.7 889.4 14491.7 15405.2 15426.9 15476.5 15547.2 15803.3 15933.4 16016.6 16022.1 16022.3 676.2 793.1 794.2 824.7 826.1 821.8 822.9 825.2 825.3 825.7 862.4 867.1 868.7 869.6 869.9 713.2 836.5 851.1 853.1 853.5 618.0 620.6 623.2 623.5 623.5 13926.4 13926.6 13936.5 13965.7 14002.6 14317.6 14652.8 14681.0 14695.9 14726.0 12036.7 12082.4 12260.4 13542.8 14010.3 4535.3 4572.2 4604.9 4616.1 4745.5 80692.6 82673.6 83029.0 83084.0 83821.2 10082.3 10137.2 10192.5 10204.5 10374.6 16724.4 16821.9 17131.0 17496.9 17607.9 8433.5 8625.1 8758.5 8769.2 8919.2 4614.4 4808.7 4894.0 4905.3 4935.3 3393.0 3505.1 3514.3 3521.3 3532.1 538.7 588.2 620.2 631.0 632.5 742.4 780.3 780.9 813.6 826.7 10442.9 10596.8 10913.0 11004.7 11037.3 753.2 764.2 766.6 777.2 781.9 5031.6 5088.2 5111.3 5151.8 5169.9 116141.4 127057.0 128837.0 130240.0 132325.0 69488.3 85500.0 87040.6 102326.0 110428.4 78755.0 80442.0 89605.6 91012.1 91769.0 10056.5 10131.4 10344.8 10783.2 10813.9 39019.4 43524.6 50691.7 52527.0 53436.1 43504.0 43551.4 50450.7 75082.6 80511.6 12508.8 12526.6 12985.6 13228.1 13415.9 10845.5 14090.1 14495.3 14984.9 15254.1 14658.5 14700.2 14807.9 15012.8 15671.2 12551.6 13122.1 13275.8 13312.4 13657.5 13039.7 13718.3 13894.6 14054.6 14329.5 12249.5 12252.6 12263.3 12276.8 12381.2 11844.5 12027.2 12186.6 12260.9 12397.6 05:42:35 INFO - PROCESS | 7220 | -------- Summary: end -------- 05:42:35 INFO - PROCESS | 7220 | 05:42:36 INFO - TEST-INFO | 7220: exit 0 05:42:36 INFO - TEST-OK | dromaeo_css | took 1113200ms 05:42:36 INFO - TEST-START | kraken 05:42:36 INFO - operating with platform_type : linux_ 05:42:36 INFO - Initialising browser for kraken test... 05:42:36 INFO - TEST-INFO | started process 7410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQDAaX/profile http://localhost:44557/getInfo.html) 05:42:40 INFO - PROCESS | 7410 | __metrics Screen width/height:1600/1200 05:42:40 INFO - PROCESS | 7410 | colorDepth:24 05:42:40 INFO - PROCESS | 7410 | Browser inner width/height: 1024/697 05:42:40 INFO - PROCESS | 7410 | __metrics 05:42:41 INFO - PROCESS | 7410 | 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" 05:42:41 INFO - PROCESS | 7410 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:42:42 INFO - TEST-INFO | 7410: exit 0 05:42:42 INFO - Browser initialized. 05:42:42 INFO - Running cycle 1/1 for kraken test... 05:42:42 INFO - TEST-INFO | started process 7491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQDAaX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:54 INFO - PROCESS | 7491 | RSS: Main: 157573120 05:42:54 INFO - PROCESS | 7491 | 05:42:56 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44557/tests/kraken/driver.html?testName=audio-beat-detection) 05:42:57 INFO - PROCESS | 7491 | RSS: Main: 215302144 05:42:57 INFO - PROCESS | 7491 | 05:43:00 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44557/tests/kraken/driver.html?testName=audio-dft) 05:43:00 INFO - PROCESS | 7491 | RSS: Main: 203161600 05:43:00 INFO - PROCESS | 7491 | 05:43:03 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44557/tests/kraken/driver.html?testName=audio-fft) 05:43:04 INFO - PROCESS | 7491 | RSS: Main: 269746176 05:43:04 INFO - PROCESS | 7491 | 05:43:06 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44557/tests/kraken/driver.html?testName=audio-oscillator) 05:43:06 INFO - PROCESS | 7491 | RSS: Main: 180613120 05:43:06 INFO - PROCESS | 7491 | 05:43:08 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44557/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:09 INFO - PROCESS | 7491 | RSS: Main: 185122816 05:43:09 INFO - PROCESS | 7491 | 05:43:12 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44557/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:13 INFO - PROCESS | 7491 | RSS: Main: 218849280 05:43:13 INFO - PROCESS | 7491 | 05:43:16 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44557/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:17 INFO - PROCESS | 7491 | RSS: Main: 221327360 05:43:17 INFO - PROCESS | 7491 | 05:43:19 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44557/tests/kraken/driver.html?testName=json-parse-financial) 05:43:20 INFO - PROCESS | 7491 | RSS: Main: 220692480 05:43:20 INFO - PROCESS | 7491 | 05:43:22 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44557/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:22 INFO - PROCESS | 7491 | RSS: Main: 208564224 05:43:22 INFO - PROCESS | 7491 | 05:43:24 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:25 INFO - PROCESS | 7491 | RSS: Main: 212447232 05:43:25 INFO - PROCESS | 7491 | 05:43:26 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:27 INFO - PROCESS | 7491 | RSS: Main: 209612800 05:43:27 INFO - PROCESS | 7491 | 05:43:30 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:30 INFO - PROCESS | 7491 | RSS: Main: 221118464 05:43:30 INFO - PROCESS | 7491 | 05:43:33 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:34 INFO - PROCESS | 7491 | RSS: Main: 222945280 05:43:34 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | Cycle 1(1): loaded http://localhost:44557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44557/tests/kraken/driver.html?testName=ai-astar) 05:43:35 INFO - PROCESS | 7491 | RSS: Main: 265822208 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | __start_tp_report 05:43:35 INFO - PROCESS | 7491 | _x_x_mozilla_page_load 05:43:35 INFO - PROCESS | 7491 | _x_x_mozilla_page_load_details 05:43:35 INFO - PROCESS | 7491 | |i|pagename|runs| 05:43:35 INFO - PROCESS | 7491 | |0;ai-astar;101;110;109;110;111;112;110;111;111;110 05:43:35 INFO - PROCESS | 7491 | |1;audio-beat-detection;150;120;118;167;119;121;140;119;163;115 05:43:35 INFO - PROCESS | 7491 | |2;audio-dft;161;162;160;161;160;161;163;161;163;161 05:43:35 INFO - PROCESS | 7491 | |3;audio-fft;76;73;75;124;76;76;74;74;116;74 05:43:35 INFO - PROCESS | 7491 | |4;audio-oscillator;84;85;116;85;85;84;118;84;84;124 05:43:35 INFO - PROCESS | 7491 | |5;imaging-gaussian-blur;117;116;116;116;117;117;117;116;116;116 05:43:35 INFO - PROCESS | 7491 | |6;imaging-darkroom;146;146;146;145;145;145;146;146;147;145 05:43:35 INFO - PROCESS | 7491 | |7;imaging-desaturate;95;95;95;95;96;95;94;95;95;95 05:43:35 INFO - PROCESS | 7491 | |8;json-parse-financial;54;53;53;53;54;53;54;55;54;56 05:43:35 INFO - PROCESS | 7491 | |9;json-stringify-tinderbox;64;63;63;62;63;64;63;63;63;63 05:43:35 INFO - PROCESS | 7491 | |10;stanford-crypto-aes;76;75;75;76;76;77;76;80;75;81 05:43:35 INFO - PROCESS | 7491 | |11;stanford-crypto-ccm;123;124;120;120;119;121;121;124;160;121 05:43:35 INFO - PROCESS | 7491 | |12;stanford-crypto-pbkdf2;157;160;158;157;157;161;157;155;160;155 05:43:35 INFO - PROCESS | 7491 | |13;stanford-crypto-sha256-iterative;68;63;63;63;63;62;62;64;61;61 05:43:35 INFO - PROCESS | 7491 | __end_tp_report 05:43:35 INFO - PROCESS | 7491 | __start_cc_report 05:43:35 INFO - PROCESS | 7491 | _x_x_mozilla_cycle_collect,1643 05:43:35 INFO - PROCESS | 7491 | __end_cc_report 05:43:35 INFO - PROCESS | 7491 | __startTimestamp1468241015966__endTimestamp 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | ------- Summary: start ------- 05:43:35 INFO - PROCESS | 7491 | Number of tests: 14 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.50 stddev:3.10 (2.8%) stddev-sans-first:0.88 05:43:35 INFO - PROCESS | 7491 | Values: 101.0 110.0 109.0 110.0 111.0 112.0 110.0 111.0 111.0 110.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:130.50 stddev:20.13 (15.4%) stddev-sans-first:20.41 05:43:35 INFO - PROCESS | 7491 | Values: 150.0 120.0 118.0 167.0 119.0 121.0 140.0 119.0 163.0 115.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 05:43:35 INFO - PROCESS | 7491 | Values: 161.0 162.0 160.0 161.0 160.0 161.0 163.0 161.0 163.0 161.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#3] audio-fft Cycles:10 Average:83.80 Median:74.50 stddev:19.20 (25.8%) stddev-sans-first:20.16 05:43:35 INFO - PROCESS | 7491 | Values: 76.0 73.0 75.0 124.0 76.0 76.0 74.0 74.0 116.0 74.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.00 stddev:16.98 (20.2%) stddev-sans-first:17.55 05:43:35 INFO - PROCESS | 7491 | Values: 84.0 85.0 116.0 85.0 85.0 84.0 118.0 84.0 84.0 124.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:43:35 INFO - PROCESS | 7491 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 05:43:35 INFO - PROCESS | 7491 | Values: 146.0 146.0 146.0 145.0 145.0 145.0 146.0 146.0 147.0 145.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 05:43:35 INFO - PROCESS | 7491 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 05:43:35 INFO - PROCESS | 7491 | Values: 54.0 53.0 53.0 53.0 54.0 53.0 54.0 55.0 54.0 56.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 05:43:35 INFO - PROCESS | 7491 | Values: 64.0 63.0 63.0 62.0 63.0 64.0 63.0 63.0 63.0 63.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:76.00 stddev:2.11 (2.8%) stddev-sans-first:2.22 05:43:35 INFO - PROCESS | 7491 | Values: 76.0 75.0 75.0 76.0 76.0 77.0 76.0 80.0 75.0 81.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:122.00 stddev:12.31 (10.1%) stddev-sans-first:13.03 05:43:35 INFO - PROCESS | 7491 | Values: 123.0 124.0 120.0 120.0 119.0 121.0 121.0 124.0 160.0 121.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.70 Median:157.50 stddev:2.06 (1.3%) stddev-sans-first:2.17 05:43:35 INFO - PROCESS | 7491 | Values: 157.0 160.0 158.0 157.0 157.0 161.0 157.0 155.0 160.0 155.0 05:43:35 INFO - PROCESS | 7491 | 05:43:35 INFO - PROCESS | 7491 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:2.00 (3.2%) stddev-sans-first:1.01 05:43:35 INFO - PROCESS | 7491 | Values: 68.0 63.0 63.0 63.0 63.0 62.0 62.0 64.0 61.0 61.0 05:43:35 INFO - PROCESS | 7491 | -------- Summary: end -------- 05:43:35 INFO - PROCESS | 7491 | 05:43:36 INFO - TEST-INFO | 7491: exit 0 05:43:36 INFO - TEST-OK | kraken | took 60551ms 05:43:36 INFO - SUITE-END | took 1173s 05:43:37 INFO - Completed test suite (00:19:33) 05:43:37 INFO - javascript benchmark 05:43:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3010.6387233531973, 3047.055412822027, 3057.6789437109105, 3072.969270307297, 3074.8616312265945, 5177.352830896138, 5301.019311427389, 5354.330708661418, 5446.346438427389, 5464.262324586185, 5248.685078895269, 5332.226827110067, 5361.490658387456, 5374.086405311096, 5387.218853266274, 4845.127876982142, 4847.054824309261, 4926.926096108561, 4942.901141977159, 5043.5965122790085, 4946.431160416544, 4948.901021979558, 4963.053818769252, 4999.3000979862845, 5000.624953128512, 4756.262779269202, 4825.589824864891, 4834.637402194814, 4862.6109911207195, 4890.902181956341, 4810.7835147418455, 4828.203346447832, 4906.901861962777, 4917.4344950330815, 4959.305697202394, 4783.880402989918, 4870.342503762017, 4876.049170411771, 4888.853334399973, 4919.26211068339, 5291.047611429941, 5374.731263436813, 5374.973125134349, 5418.241446197536, 5478.424765399615, 5809.6804675742815, 5833.883322333572, 5849.327327357366, 5855.795047173371, 5859.033259512175, 5627.296587926509, 5751.166080918285, 5849.736761845685, 5911.231539899829, 5916.645001299893, 5458.645188062763, 5488.286522752057, 5490.972545137329, 5491.176323551499, 5494.642848214813, 5956.7319470623925, 5965.28416590012, 5995.160677505153, 5996.220491336098, 5996.490298324604, 5548.667079975215, 5641.576881733887, 5660.264165658438, 5724.771009159587, 5740.54075673945, 5812.651240925558, 5814.069748840166, 5856.765729370863, 5866.560007999417, 5872.500837428781, 5726.942730572641, 5737.282839645044, 5785.942140578624, 5794.50746686528, 5840.970795145997, 5515.669059856422, 5647.124695672177, 5686.60193786431, 5704.914426283609, 5768.538516918637, 4393.890152746207, 4402.427684401007, 4406.713563618355, 4407.096545208225, 4438.822447102079, 3903.2583809076186, 3918.921621567553, 3920.5099362579676, 3962.980185099114, 3973.4039894016128, 5277.525022747971, 5336.439673834191, 5369.677819330853, 5391.191321301836, 5407.378151512627, 5743.971280143656, 5755.597108202385, 5769.653820770767, 5770.826875193751, 5788.27646544182, 5344.412114667383, 5473.31583552056, 5478.123500239943, 5480.369757477862, 5480.835574932758, 5016.473270306638, 5051.797928082909, 5076.238564215398, 5095.97452012745, 5100.030994111107, 6102.267727872683, 6102.603330783573, 6109.786157484556, 6119.357467465941, 6124.5100391968535, 5608.242887210337, 5612.7754889803955, 5864.44287792659, 5887.028640274478, 5888.205092312654, 5826.096954971986, 5933.406659334032, 5940.79207227745, 5943.821685349447, 5944.3164036136395, 5535.944640553543, 5587.944120558743, 5588.022096133242, 5590.692511911884, 5608.214849921096, 5608.8878222434505, 5659.236003139524, 5666.603337766317, 5706.714664266687, 5707.8858422832145, 5586.776528938797, 5590.2453168821685, 5593.524550413098, 5632.971835140962, 5638.097904335286, 5501.669899806025, 5505.889882202414, 5533.86555756061, 5551.472610102034, 5553.9725150847225, 5375.865603359791, 5520.254765606592, 5567.331920169606, 5581.162825576196, 5595.692236927008, 5728.79949201776, 5731.799387021434, 5775.711214439345, 5781.537477001746, 5786.855328616818, 5580.525655319181, 5618.2415373924, 5624.690642014565, 5625.156226566048, 5629.240052592848], "value": 5313.277599478296, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.5808205215321, 614.7110857896788, 619.2074145094281, 632.09610257332, 637.7417146055848, 35338.116547086305, 35911.640883591186, 36005.099872503204, 42507.36238956418, 43387.13225735483, 44815.77592112043, 45383.0, 45406.0, 45426.0, 45483.77258113705, 8904.599293031797, 9105.772355691095, 9114.13415725505, 9118.954405227965, 9132.0, 8800.559972001374, 8818.911810881906, 8903.24322432594, 8906.153915378061, 8910.42082264654, 8966.193042626162, 8992.370534062618, 9000.77498062547, 9001.189892909637, 9002.32482563807, 8904.287656987457, 8956.0, 8966.462012279284, 8969.820603587925, 8971.192592666692, 8873.11268873114, 8934.06192349807, 8937.6871809487, 8940.46357218569, 8947.50788706621, 43364.78317608392, 43878.78060609676, 43910.78044609789, 43935.78032109851, 44037.0, 3706.5181526401666, 3741.5884252732176, 3807.505024346817, 3838.8848334550007, 3870.7290489665734, 32507.674923250703, 32645.836770816233, 33223.16942076467, 33866.83066584676, 34272.657273427445, 4349.80425880833, 4362.738235705868, 4366.432363792718, 4421.137878113769, 4515.14212299662, 363196.0, 364669.0, 364813.0, 366374.0, 367797.0, 34351.82824085913, 35263.294734105686, 35291.823540882135, 58201.417985820444, 58650.70674646599, 37199.62800372016, 37218.62781372206, 37289.0, 37313.81343093267, 37353.0, 15655.608609784618, 15756.448524301824, 15785.052896826228, 15787.921060394625, 15792.289346979414, 13282.800757988638, 13345.132566383349, 13374.732505349839, 13384.93307533456, 13392.397342119628, 8459.873101903353, 8465.111163327885, 8481.194286542768, 8522.74431767048, 8539.316854651614, 3255.348930213919, 3264.7224985876464, 3272.1001724525468, 3294.341131773655, 3335.0661814691925, 957.5212393803098, 982.9311948163703, 1038.309524166436, 1093.9206134730707, 1149.270213414488, 32509.51235731466, 32761.836190818893, 33306.66693333036, 34183.11992840368, 34396.48405273923, 1083.983740243897, 1095.4030053620795, 1171.4552732979205, 1176.6822957801346, 1178.6287319494331, 16978.23597938096, 17032.65934681324, 17143.485695429157, 17144.485665430057, 17256.913715431594, 568493.0, 573031.0, 579252.0, 579792.0, 581525.0, 57398.0, 58043.419565804645, 58145.12782308269, 58227.70886145542, 58280.708596456745, 1260.7163388237757, 1333.093496422442, 1381.951631692886, 1388.3058470764618, 1409.9506517271845, 31834.522482162323, 33687.0, 34066.318673625894, 34450.48324275188, 34497.827510862284, 50964.23553646773, 51318.48681513286, 51338.48661513337, 51725.0, 51757.482425175265, 432654.0, 435623.0, 435670.0, 435917.0, 436245.0, 24298.0, 26734.0, 27254.863725681244, 27646.44707105887, 27773.0, 46627.0, 47044.52955470538, 47181.29228061513, 47726.04547909092, 48013.5198648023, 40809.0, 40815.7959210202, 40914.0, 41398.58601413947, 42443.36334954917, 40106.39840402455, 40899.0, 41378.0, 41668.583314167685, 42425.0, 40328.79835600803, 40690.59309406987, 40895.1820963585, 41069.38395924123, 41308.0, 34573.827130864185, 35772.414446111055, 35842.31525040137, 36380.81809590935, 36621.26757464889, 27121.86439067792, 27577.724222757515, 27617.171484855488, 27719.445611087263, 27805.582916256677], "value": 19009.925331409, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.32881594513236, 398.67507980995504, 403.23788040603245, 407.56797794338, 418.988143633126, 13745.65635859104, 18007.81992180078, 19570.804291957094, 19617.21531138753, 19979.800201997976, 15057.322420491077, 19434.0, 19457.41627751166, 20396.796032039674, 20794.0, 5743.0, 5878.470937615613, 5914.792982245623, 5923.111533270023, 5949.613275137102, 4942.777575009115, 5789.45000224978, 5915.792947246847, 5915.9704201478935, 5923.822285331447, 4246.2356775780345, 6268.686565671698, 6545.214574251097, 6549.345065493437, 6601.702923368436, 5631.324241091054, 5941.584089113764, 6584.604923704569, 6628.900566491507, 6637.800865974028, 5720.742566584472, 5772.076467765138, 5804.796832110898, 5820.243368362128, 5828.329742079631, 14336.56990290293, 15331.080135191813, 21907.561848763096, 22876.65685014726, 23109.653355199687, 2119.5337025854287, 2197.6923230747548, 2738.9178324650093, 2984.2986898078934, 3016.7737419693613, 11051.834222486668, 11778.823317650242, 13905.16569005863, 14849.406023759037, 15437.536873893801, 1964.7052942058663, 1982.7719812221635, 1996.3212507747435, 2002.1590931808676, 2008.5179556906382, 77818.6109069451, 84530.0, 84800.57599712024, 85138.57430712869, 85497.74027116723, 10957.83563246552, 11097.500612472459, 11215.775684486265, 11235.61810893752, 11842.230255033395, 15543.067415955078, 15563.610909727347, 15644.608884777972, 15680.294386752623, 15692.33230350843, 8269.131741167092, 8344.207300306462, 8356.540390278593, 8430.747077587683, 8504.957475212585, 7895.802604934807, 7919.554993577812, 8016.39877009215, 8100.432969692065, 8164.836703265902, 4479.3057076153245, 4831.444383895827, 4848.248521479177, 4924.532169443897, 8335.624621937312, 3183.1564635371647, 3340.8162551059927, 3377.6453472385538, 3386.847591858372, 3562.590302115238, 915.4415806358116, 976.1214906583989, 976.7313988653177, 1023.3655133816932, 1036.3056751976194, 19847.404577862686, 20752.896235518725, 20760.896195518926, 20809.687854682194, 20945.476363091046, 997.6358629100471, 1011.5903059261011, 1014.2190513304862, 1019.928604997643, 1051.4164638625484, 10918.563257469612, 11378.089752819755, 11453.828192577119, 11461.54153833854, 11487.483063262172, 450764.0, 452177.0, 452179.0, 454618.0, 469501.0, 92841.0, 96965.0, 97214.0, 97336.51331743295, 99020.5048974765, 952.2810278239884, 970.8301047316692, 995.233670074039, 1000.2698030437821, 1001.0490034467285, 17786.288548457916, 17875.821241787937, 18204.0, 18207.635847282716, 18607.720884186478, 44897.32654010127, 45849.312260315455, 46052.7697361511, 47256.7637161812, 47462.288065679735, 63468.682656586425, 63852.68073659602, 64585.677071614344, 65628.67185664232, 66546.0, 8932.598033088396, 9121.589528471102, 9229.630814767603, 9299.116583924517, 9413.670521531714, 13389.330533473481, 13986.230757308445, 14114.858851411354, 14362.353694083582, 14617.196054216694, 11978.401079945794, 12064.93967530192, 12097.57658481949, 12105.092118091003, 12278.0, 12202.14584979043, 12250.938745306217, 12321.815172772236, 12368.505259789508, 12447.394702453974, 11738.354390508604, 12732.936335318635, 13038.608841734764, 13099.607011789662, 13111.672208194872, 8770.47377157347, 8770.618033444287, 8843.955780221058, 8906.777330566787, 9326.347155699035, 9161.312901532545, 11898.0, 12306.323152226714, 12444.128910976508, 12712.427940742504], "value": 9726.40208166668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.716349566591, 1459.7591397419426, 1516.052467207995, 1615.6849414364203, 1756.771619689441, 8068.31419329357, 8307.335413166948, 8339.24946754792, 8592.226699597035, 8601.569921503931, 8401.621927013284, 8615.741527754177, 8619.612117454713, 8634.136586341363, 8636.093210212915, 1982.2467462364282, 2287.9885600572056, 2338.1582630253097, 2359.5162991586685, 2372.525494901018, 2360.2919124262653, 2377.2749311459997, 2378.15575470708, 2378.8810559472045, 2381.4403615150513, 2342.590046741822, 2357.1985524921524, 2368.46709490365, 2371.34787933318, 2373.9169129080487, 1948.7758907725581, 2172.0768673313937, 2239.708837851069, 2244.046280330869, 2262.0, 2290.530441259538, 2300.4478925058033, 2317.895694693743, 2318.466752646884, 2319.095552734435, 8933.0, 9051.04963978786, 9052.049534798884, 9062.682806101755, 9074.818503629956, 5181.5854731621375, 5317.308749862532, 5319.388270348921, 5321.228421878806, 5322.414534401251, 3374.645662205482, 3414.5048967899766, 3481.3385456763135, 3510.438329867209, 3569.821508924543, 1099.934003959765, 1115.4868760370239, 1120.5965852293168, 1121.6522877907876, 1126.5268587193398, 12625.684357891034, 12630.557930472482, 12631.242125472501, 12641.87358126416, 12650.304233267074, 4639.976800115978, 4696.412948381452, 4749.371464487719, 4762.690425122356, 4883.365162528919, 10489.0, 10490.370577765358, 10491.790164196826, 10500.474976251156, 10518.73703157412, 5375.4624537546715, 5376.542993845567, 5378.408375078739, 5382.0, 5393.7033463159905, 4153.0, 4797.4482934462985, 4931.876703082452, 4955.950440495621, 4960.503949605083, 4006.25884211416, 4052.0, 4107.856225032169, 4161.771102589387, 4215.451991241181, 1122.259308856151, 1292.954746583865, 1306.6145487081144, 1308.535469908188, 1325.6221976736583, 902.9909700902907, 916.6058594804165, 964.2767924056957, 967.2117224462041, 1007.969760907174, 5738.426157384228, 6044.576879618472, 6059.575829691968, 6133.233345831771, 6151.938480615226, 949.482532020037, 977.6920270114891, 989.5200827598609, 997.7605374710163, 1008.9495525223709, 3994.4407782910416, 4031.8387264509092, 4031.959680403217, 4070.9796451017555, 4081.285774989424, 10269.0, 10624.096951759188, 10720.785584288271, 10725.678229653124, 10731.356118632906, 10614.840777388345, 10622.628208012682, 10631.627893023708, 10643.0, 10645.467726613793, 8033.5983200838555, 8067.39494537912, 8136.389770767216, 8147.877781833277, 8157.3474122071275, 5726.341470730919, 5796.449337312949, 5824.737886795019, 5857.941420585739, 5930.436608522184, 9940.950295248478, 10033.397996120257, 10034.397936123856, 10077.496125193564, 10103.0, 10344.37933723979, 10538.315009524356, 10550.788984220426, 10634.202434817575, 10661.360318380923, 7285.235050319852, 8219.136990616138, 8525.0, 8567.61445734931, 8755.080716524928, 8854.690085847222, 8928.0, 8951.552422378725, 8999.055099214751, 9004.054574269607, 7425.554466732014, 7500.287472690085, 7592.544447333178, 7628.0083589133055, 7874.251946065115, 7622.961885190539, 7700.807479813049, 7807.960960195162, 7809.062912450542, 7846.215378462336, 7185.353318201387, 7218.494705370784, 7250.166230883516, 7254.056972593529, 7256.600886951268, 4872.731999740048, 5290.100682883842, 5291.656042357235, 5369.167778994262, 5455.945440545703, 5295.7087360195555, 5311.893762124813, 5359.517643412111, 5446.727663616724, 5448.0], "value": 4672.714071831265, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.2217632382818, 339.78933061501874, 345.73896707985466, 347.0525465479243, 349.655589244594, 5146.794128234867, 5241.0, 5300.708461034642, 5357.49103835136, 5396.0, 5242.012053129708, 5278.419373868881, 5356.38401583818, 5364.865878353043, 5387.488188622085, 4206.179794939981, 4217.683673724468, 4220.345846393814, 4221.113566151096, 4227.408162857202, 4015.6988225883174, 4060.8375664973514, 4125.504939407276, 4229.090745489719, 4229.175310814392, 4159.355299928516, 4185.100203456272, 4186.3092589722655, 4205.612147991155, 4212.862527117676, 4173.1862286854075, 4175.269327867611, 4198.160367926412, 4206.432131662217, 4206.936895946563, 3611.6388361163936, 3692.833822477995, 3738.7943663098517, 3741.27045226181, 3759.9624003759886, 5119.820806271762, 5130.179171332569, 5133.51231632998, 5140.280360749498, 5194.532492075694, 4720.457147428056, 4749.715017098985, 4776.808927642925, 4777.80888764449, 4782.450018247911, 5293.4971177738435, 5437.009644695402, 5465.289512363407, 5498.560115190815, 5518.944810551883, 3940.9950462631846, 3943.802809859524, 3947.072437977073, 3951.8221680024467, 3968.7420317679257, 5578.577462747014, 5636.182753500761, 5707.942920570783, 5724.227229324071, 5753.194552762575, 3959.3071212538266, 4022.939655905164, 4025.5243025539085, 4025.9597404025585, 4034.515858097047, 4067.6745860331694, 4083.448734420815, 4095.0, 4095.5904409559403, 4096.610821971967, 4645.605123564467, 4652.697574657636, 4663.790129444184, 4670.462896766915, 4671.95328046722, 5278.709670968057, 5457.335512668595, 5490.6980116094, 5492.5331346835965, 5555.666660000413, 1359.7008658095203, 1388.1532265318149, 1403.4316101978716, 1408.6689627937426, 1410.168000879486, 954.6610953111546, 974.2936371130874, 984.1831280037552, 988.847992089208, 992.0575453319375, 5260.605454590921, 5279.577633789288, 5311.0, 5332.9466705333225, 5340.732963351817, 5519.199716041152, 5527.198556209327, 5538.889222215533, 5543.889122217533, 5552.555795536347, 5127.64106512548, 5236.114998600501, 5289.920651190235, 5312.442193569696, 5340.2256672783005, 2637.95558442205, 2684.4228490874557, 2685.9462810743676, 2687.462507498469, 2688.3735340119338, 5146.614003949719, 5150.227465880148, 5173.4826517347965, 5186.936677981005, 5248.711320877388, 4863.319135320987, 5087.9061001884775, 5101.566366858784, 5109.97800439906, 5112.182050871917, 5144.73953881294, 5168.276441298296, 5182.274481572658, 5190.0, 5205.9739701302005, 4995.450500444876, 5033.892543640393, 5037.891663833951, 5042.26042015504, 5047.570956468594, 5031.572316353005, 5226.712530810842, 5720.656760594377, 5733.168690539932, 5758.827235182951, 6012.849678758066, 6032.547558933185, 6041.918621502978, 6046.697665116639, 6047.455728984412, 4003.279409706281, 4084.142330110591, 4091.6522095622204, 4102.364133559303, 4103.876883693494, 5380.946190538201, 5553.9175757455005, 5728.112142617901, 5734.627249228787, 5785.710714464176, 5530.695811730233, 5540.529055030368, 5558.805441809679, 5572.359178694502, 5615.034737841723, 5586.608937374344, 5608.775648973995, 5610.438956104357, 5626.915596266142, 5627.8593035174445, 5274.547335488863, 5495.845872366848, 5560.638558493685, 5568.721563921868, 5586.86032849182, 5584.776608935597, 5592.524635405874, 5639.88720225585, 5643.689597072036, 5667.574931880043, 5170.560502357342, 5503.2020357048805, 5506.614536982373, 5514.779408823762, 5539.8892022160135], "value": 4169.900623098007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.3630459652402, 757.984840303194, 766.881133424319, 809.7004108479863, 889.3507739350276, 14491.710165796678, 15405.15271660061, 15426.922865385672, 15476.458323958665, 15547.222638868068, 15803.288852001659, 15933.362665493396, 16016.59958501038, 16022.118783466905, 16022.278997445113, 676.1818199978007, 793.1473665809249, 794.1819925476753, 824.686619084747, 826.0871736730916, 821.7699044267615, 822.9053658829251, 825.2366560931144, 825.3108654273659, 825.694493037577, 862.3833958719514, 867.1242045533993, 868.6916144768611, 869.5565261716507, 869.9347548933823, 713.19052874987, 836.481381543444, 851.1105894340429, 853.0573716043806, 853.4580541356283, 617.9783707570214, 620.5997131849977, 623.1680706257152, 623.5136593457065, 623.5479277523805, 13926.373313200931, 13926.58220253394, 13936.51222207228, 13965.720685586231, 14002.57992260234, 14317.57047288583, 14652.780208296885, 14681.0, 14695.926520367437, 14726.0, 12036.699082522919, 12082.395880205955, 12260.44827982743, 13542.796858047137, 14010.29948502581, 4535.274356103007, 4572.199865023607, 4604.907901841978, 4616.0767846430845, 4745.549172828576, 80692.59653701753, 82673.58663206706, 83029.0, 83084.0, 83821.16178838255, 10082.29423940317, 10137.239707022003, 10192.541335639915, 10204.489775511194, 10374.636887708893, 16724.414645487348, 16821.91589042047, 17131.0, 17496.91251543734, 17607.911960440117, 8433.536155511383, 8625.0943650917, 8758.474491530402, 8769.166929141722, 8919.152680495343, 4614.353990441341, 4808.711477311373, 4893.975530122327, 4905.337779399807, 4935.346758378986, 3393.0, 3505.1237190702323, 3514.262004978932, 3521.2605352876185, 3532.1346270163976, 538.71178919278, 588.1883001458046, 620.1751670278519, 630.9747610095636, 632.5098049012053, 742.38011260598, 780.2587541835165, 780.8711562592222, 813.5972693516629, 826.669332267098, 10442.947785261025, 10596.841047384296, 10913.0, 11004.724881878017, 11037.33775973428, 753.15270320889, 764.2319468933797, 766.577997814047, 777.2111307023375, 781.9022622172229, 5031.6163055159395, 5088.236764485357, 5111.258867464271, 5151.793928242828, 5169.870753231199, 116141.419292903, 127057.0, 128837.0, 130240.0, 132325.0, 69488.30511694818, 85500.0, 87040.56479717688, 102326.0, 110428.4478577602, 78755.0, 80442.0, 89605.55197223971, 91012.08987910036, 91769.0, 10056.491526271118, 10131.392116473035, 10344.793104137725, 10783.245172837826, 10813.891861081289, 39019.41470877882, 43524.56475435205, 50691.74654126706, 52527.0, 53436.122026577825, 43504.0, 43551.38691678509, 50450.72971716208, 75082.62458687671, 80511.59744201241, 12508.812367814671, 12526.561570344995, 12985.610431687064, 13228.07403481747, 13415.865841341853, 10845.457727113771, 14090.084144530572, 14495.275236238356, 14984.850151498345, 15254.084754914296, 14658.486952956595, 14700.191489468181, 14807.851921481079, 15012.849871501145, 15671.216439177768, 12551.560695375618, 13122.14706044104, 13275.80086298687, 13312.400941957829, 13657.521986730417, 13039.674008149872, 13718.314084295946, 13894.583162505141, 14054.648633784238, 14329.498467554002, 12249.510019599116, 12252.571160009356, 12263.313756967184, 12276.754464910831, 12381.195222310522, 11844.546527894745, 12027.15809893299, 12186.634400967985, 12260.938695306466, 12397.566085186974], "value": 7637.793412613893, "unit": "score"}], "name": "dromaeo_css", "value": 7258.2794964731365, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 109.0, 110.0, 111.0, 112.0, 110.0, 111.0, 111.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 118.0, 167.0, 119.0, 121.0, 140.0, 119.0, 163.0, 115.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 161.0, 163.0, 161.0, 163.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 75.0, 124.0, 76.0, 76.0, 74.0, 74.0, 116.0, 74.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 116.0, 85.0, 85.0, 84.0, 118.0, 84.0, 84.0, 124.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 147.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, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 54.0, 53.0, 54.0, 55.0, 54.0, 56.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 76.0, 76.0, 77.0, 76.0, 80.0, 75.0, 81.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 120.0, 120.0, 119.0, 121.0, 121.0, 124.0, 160.0, 121.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 158.0, 157.0, 157.0, 161.0, 157.0, 155.0, 160.0, 155.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 63.0, 63.0, 63.0, 62.0, 62.0, 64.0, 61.0, 61.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1479.5, "alertThreshold": 2.0}]} 05:43:37 INFO - Return code: 0 05:43:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:37 INFO - # TBPL SUCCESS # 05:43:37 INFO - Running post-action listener: _resource_record_post_action 05:43:37 INFO - ##### 05:43:37 INFO - ##### Finished run-tests step (success) 05:43:37 INFO - ##### 05:43:37 INFO - Running post-run listener: _resource_record_post_run 05:43:37 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 554258432; Read time: 12; Write time: 376460 05:43:37 INFO - TinderboxPrint: CPU usage
13.0% 05:43:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:43:37 INFO - TinderboxPrint: I/O write bytes / time
554,258,432 / 376,460 05:43:37 INFO - TinderboxPrint: CPU idle
8,273.7 (87.0%) 05:43:37 INFO - TinderboxPrint: CPU user
1,190.2 (12.5%) 05:43:37 INFO - TinderboxPrint: Swap in / out
0 / 0 05:43:37 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 263159808; Read time: 0; Write time: 224532 05:43:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 291098624; Read time: 0; Write time: 151928 05:43:37 INFO - Running post-run listener: _upload_blobber_files 05:43:37 INFO - Blob upload gear active. 05:43:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:37 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 05:43:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:43:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:43:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:43:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:39 INFO - (blobuploader) - INFO - Done attempting. 05:43:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:43 INFO - (blobuploader) - INFO - Done attempting. 05:43:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:43:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:44 INFO - (blobuploader) - INFO - Done attempting. 05:43:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:44 INFO - Return code: 0 05:43:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57033aa22330bd6c2ca9997133e6f591b7b5702d91bc0f76dcb02255c0ae4cc98761e2db3ea40f99f03946ae228314db27240835e3e2af65bd9deff2b824aef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31aa5808160391c35e9a09fd88ccfb1f98df1d7cd736c8d3f6ba9e26f38826ecb31d1638b7c9d3900ba53c15a4dbbabf44beb89a45c79398280f0eab5d534748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794a185d641ba2f2687ea6352b6cfccdcb43ecd280d3ecfaa24aa169ac96954e649f48d6abf29a9885424fe8b30c130422ec9a1375e1b61f4307a192462abeb8"} 05:43:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:44 INFO - Contents: 05:43:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57033aa22330bd6c2ca9997133e6f591b7b5702d91bc0f76dcb02255c0ae4cc98761e2db3ea40f99f03946ae228314db27240835e3e2af65bd9deff2b824aef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31aa5808160391c35e9a09fd88ccfb1f98df1d7cd736c8d3f6ba9e26f38826ecb31d1638b7c9d3900ba53c15a4dbbabf44beb89a45c79398280f0eab5d534748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794a185d641ba2f2687ea6352b6cfccdcb43ecd280d3ecfaa24aa169ac96954e649f48d6abf29a9885424fe8b30c130422ec9a1375e1b61f4307a192462abeb8"} 05:43:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:44 INFO - Copying logs to upload dir... 05:43:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.614510 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-11 05:43:43.912457) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 05:43:43.916575) ========= 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=00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57033aa22330bd6c2ca9997133e6f591b7b5702d91bc0f76dcb02255c0ae4cc98761e2db3ea40f99f03946ae228314db27240835e3e2af65bd9deff2b824aef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31aa5808160391c35e9a09fd88ccfb1f98df1d7cd736c8d3f6ba9e26f38826ecb31d1638b7c9d3900ba53c15a4dbbabf44beb89a45c79398280f0eab5d534748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794a185d641ba2f2687ea6352b6cfccdcb43ecd280d3ecfaa24aa169ac96954e649f48d6abf29a9885424fe8b30c130422ec9a1375e1b61f4307a192462abeb8"} build_url:https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://queue.taskcluster.net/v1/task/coEJbLhqTci38c6RWN6rzw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a57033aa22330bd6c2ca9997133e6f591b7b5702d91bc0f76dcb02255c0ae4cc98761e2db3ea40f99f03946ae228314db27240835e3e2af65bd9deff2b824aef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31aa5808160391c35e9a09fd88ccfb1f98df1d7cd736c8d3f6ba9e26f38826ecb31d1638b7c9d3900ba53c15a4dbbabf44beb89a45c79398280f0eab5d534748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794a185d641ba2f2687ea6352b6cfccdcb43ecd280d3ecfaa24aa169ac96954e649f48d6abf29a9885424fe8b30c130422ec9a1375e1b61f4307a192462abeb8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 05:43:43.945083) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:43:43.945708) ========= 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=00204705a9daa57ed339a427000001b9-1468239788.995639-1651285999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:43:44.029745) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 05:43:44.030582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 05:43:44.031259) ========= ========= Total master_lag: 0.20 =========