builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1468328219.19 results: success (0) buildid: 20160712025529 builduid: 956ac789f8e5444594c9ad8d4c411d84 revision: 940674ad1be3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.190171) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.190643) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.190919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.205574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.205841) ========= 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=62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 05:56:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-07-12 05:56:59 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077196 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.297001) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.297324) ========= 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=62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016671 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.347486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:56:59.347831) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 940674ad1be3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 940674ad1be3 --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=62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 05:56:59,786 Setting DEBUG logging. 2016-07-12 05:56:59,786 attempt 1/10 2016-07-12 05:56:59,786 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/940674ad1be3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 05:57:00,114 unpacking tar archive at: mozilla-inbound-940674ad1be3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.672892 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:57:00.036756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 05:57:00.037286) ========= script_repo_revision: 940674ad1be3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 05:57:00.037913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 05:57:00.038397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 05:57:00.050130) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-12 05:57:00.050673) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:00 INFO - MultiFileLogger online at 20160712 05:57:00 in /builds/slave/test 05:57:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:57:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:00 INFO - {'append_to_log': False, 05:57:00 INFO - 'base_work_dir': '/builds/slave/test', 05:57:00 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:57:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:00 INFO - 'branch': 'Mozilla-Inbound', 05:57:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:00 INFO - 'config_files': ('talos/linux_config.py',), 05:57:00 INFO - 'default_actions': ('clobber', 05:57:00 INFO - 'read-buildbot-config', 05:57:00 INFO - 'download-and-extract', 05:57:00 INFO - 'populate-webroot', 05:57:00 INFO - 'create-virtualenv', 05:57:00 INFO - 'install', 05:57:00 INFO - 'run-tests'), 05:57:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:00 INFO - 'download_minidump_stackwalk': True, 05:57:00 INFO - 'download_symbols': 'ondemand', 05:57:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:00 INFO - 'installer_path': 'installer.exe', 05:57:00 INFO - 'log_level': 'info', 05:57:00 INFO - 'log_name': 'talos', 05:57:00 INFO - 'log_to_console': True, 05:57:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:00 INFO - 'opt_config_files': (), 05:57:00 INFO - 'pip_index': False, 05:57:00 INFO - 'sps_profile': False, 05:57:00 INFO - 'sps_profile_interval': 0, 05:57:00 INFO - 'suite': 'dromaeojs', 05:57:00 INFO - 'system_bits': '32', 05:57:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:00 INFO - 'title': 'talos-linux64-ix-018', 05:57:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:00 INFO - 'use_talos_json': True, 05:57:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:00 INFO - 'work_dir': 'build'} 05:57:00 INFO - ##### 05:57:00 INFO - ##### Running clobber step. 05:57:00 INFO - ##### 05:57:00 INFO - Running pre-action listener: _resource_record_pre_action 05:57:00 INFO - Running main action method: clobber 05:57:00 INFO - rmtree: /builds/slave/test/build 05:57:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:01 INFO - Running post-action listener: _resource_record_post_action 05:57:01 INFO - ##### 05:57:01 INFO - ##### Finished clobber step (success) 05:57:01 INFO - ##### 05:57:01 INFO - ##### 05:57:01 INFO - ##### Running read-buildbot-config step. 05:57:01 INFO - ##### 05:57:01 INFO - Running pre-action listener: _resource_record_pre_action 05:57:01 INFO - Running main action method: read_buildbot_config 05:57:01 INFO - Using buildbot properties: 05:57:01 INFO - { 05:57:01 INFO - "project": "", 05:57:01 INFO - "product": "firefox", 05:57:01 INFO - "script_repo_revision": "production", 05:57:01 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:57:01 INFO - "repository": "", 05:57:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:57:01 INFO - "buildid": "20160712025529", 05:57:01 INFO - "pgo_build": "True", 05:57:01 INFO - "basedir": "/builds/slave/test", 05:57:01 INFO - "buildnumber": 2052, 05:57:01 INFO - "slavename": "talos-linux64-ix-018", 05:57:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:57:01 INFO - "platform": "ubuntu64_hw", 05:57:01 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 05:57:01 INFO - "branch": "mozilla-inbound", 05:57:01 INFO - "repo_path": "integration/mozilla-inbound", 05:57:01 INFO - "revision": "940674ad1be3", 05:57:01 INFO - "stage_platform": "linux64-pgo", 05:57:01 INFO - "builduid": "956ac789f8e5444594c9ad8d4c411d84", 05:57:01 INFO - "slavebuilddir": "test" 05:57:01 INFO - } 05:57:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:57:01 INFO - Running post-action listener: _resource_record_post_action 05:57:01 INFO - ##### 05:57:01 INFO - ##### Finished read-buildbot-config step (success) 05:57:01 INFO - ##### 05:57:01 INFO - ##### 05:57:01 INFO - ##### Running download-and-extract step. 05:57:01 INFO - ##### 05:57:01 INFO - Running pre-action listener: _resource_record_pre_action 05:57:01 INFO - Running main action method: download_and_extract 05:57:01 INFO - mkdir: /builds/slave/test/build/tests 05:57:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:01 INFO - https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:57:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:57:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:57:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:02 INFO - Downloaded 1361 bytes. 05:57:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:57:02 INFO - Using the following test package requirements: 05:57:02 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:57:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:57:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:57:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'jsshell-linux-x86_64.zip'], 05:57:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:57:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:57:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:57:02 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:57:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:57:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:57:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:57: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:57:02 INFO - https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:57:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:57:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:57:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:03 INFO - Downloaded 24271711 bytes. 05:57:03 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:57:03 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:57:04 INFO - Return code: 0 05:57:04 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:57:04 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:57:04 INFO - https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:05 INFO - Downloaded 24271711 bytes. 05:57:05 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:57:05 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:57:07 INFO - Return code: 0 05:57:07 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:57:07 INFO - https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:57:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:57:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:57:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:08 INFO - Downloaded 14403317 bytes. 05:57:08 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:57:08 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:57:08 INFO - Return code: 0 05:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:08 INFO - https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:57:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:57:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:57:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SadL0tOZTCSx272bAVazXA/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:57:09 INFO - Downloaded 60488761 bytes. 05:57:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:57:09 INFO - mkdir: /builds/slave/test/properties 05:57:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:09 INFO - Contents: 05:57:09 INFO - build_url:https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:57:10 INFO - Running post-action listener: _resource_record_post_action 05:57:10 INFO - Running post-action listener: set_extra_try_arguments 05:57:10 INFO - ##### 05:57:10 INFO - ##### Finished download-and-extract step (success) 05:57:10 INFO - ##### 05:57:10 INFO - ##### 05:57:10 INFO - ##### Running populate-webroot step. 05:57:10 INFO - ##### 05:57:10 INFO - Running pre-action listener: _resource_record_pre_action 05:57:10 INFO - Running main action method: populate_webroot 05:57:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:10 INFO - '--tppagecycles', 05:57:10 INFO - '7'], 05:57:10 INFO - 'tests': ['tsvgm']}}, 05:57:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['damp', 'tps']}, 05:57:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'tests': ['damp', 'tps']}, 05:57:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['dromaeo_dom']}, 05:57:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['basic_compositor_video']}, 05:57:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['a11yr', 05:57:10 INFO - 'ts_paint', 05:57:10 INFO - 'tpaint', 05:57:10 INFO - 'sessionrestore', 05:57:10 INFO - 'sessionrestore_no_auto_restore', 05:57:10 INFO - 'tabpaint']}, 05:57:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:10 INFO - 'ts_paint', 05:57:10 INFO - 'tpaint', 05:57:10 INFO - 'sessionrestore', 05:57:10 INFO - 'sessionrestore_no_auto_restore', 05:57:10 INFO - 'tabpaint']}, 05:57:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:10 INFO - 'ts_paint', 05:57:10 INFO - 'tpaint', 05:57:10 INFO - 'sessionrestore', 05:57:10 INFO - 'sessionrestore_no_auto_restore', 05:57:10 INFO - 'tabpaint']}, 05:57:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:10 INFO - 'ts_paint', 05:57:10 INFO - 'tpaint', 05:57:10 INFO - 'sessionrestore', 05:57:10 INFO - 'sessionrestore_no_auto_restore', 05:57:10 INFO - 'tabpaint']}, 05:57:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['a11yr', 05:57:10 INFO - 'ts_paint', 05:57:10 INFO - 'tpaint', 05:57:10 INFO - 'sessionrestore', 05:57:10 INFO - 'sessionrestore_no_auto_restore', 05:57:10 INFO - 'tabpaint']}, 05:57:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['a11yr', 05:57:10 INFO - 'ts_paint', 05:57:10 INFO - 'tpaint', 05:57:10 INFO - 'sessionrestore', 05:57:10 INFO - 'sessionrestore_no_auto_restore', 05:57:10 INFO - 'tabpaint']}, 05:57:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['tsvgx', 05:57:10 INFO - 'tsvgr_opacity', 05:57:10 INFO - 'tart', 05:57:10 INFO - 'tscrollx', 05:57:10 INFO - 'cart']}, 05:57:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:10 INFO - 'tsvgr_opacity', 05:57:10 INFO - 'tart', 05:57:10 INFO - 'tscrollx', 05:57:10 INFO - 'cart']}, 05:57:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'talos_options': ['--disable-e10s'], 05:57:10 INFO - 'tests': ['tp5o']}, 05:57:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'tests': ['tp5o']}, 05:57:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'talos_options': ['--disable-e10s', 05:57:10 INFO - '--xperf_path', 05:57:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:10 INFO - 'tests': ['tp5n']}, 05:57:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:10 INFO - 'talos_options': ['--xperf_path', 05:57:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:10 INFO - 'tests': ['tp5n']}}, 05:57:10 INFO - 'talos.zip': {'path': '', 05:57:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:10 INFO - Running post-action listener: _resource_record_post_action 05:57:10 INFO - ##### 05:57:10 INFO - ##### Finished populate-webroot step (success) 05:57:10 INFO - ##### 05:57:10 INFO - ##### 05:57:10 INFO - ##### Running create-virtualenv step. 05:57:10 INFO - ##### 05:57:10 INFO - Running pre-action listener: _resource_record_pre_action 05:57:10 INFO - Running main action method: create_virtualenv 05:57:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:10 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:57:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:10 INFO - Using real prefix '/usr' 05:57:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:13 INFO - Installing pip.................done. 05:57:13 INFO - Return code: 0 05:57:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:57:13 INFO - Using env: {'DISPLAY': ':0', 05:57:13 INFO - 'HOME': '/home/cltbld', 05:57:13 INFO - 'LANG': 'en_US.UTF-8', 05:57:13 INFO - 'LANGUAGE': 'en_US:en', 05:57:13 INFO - 'LOGNAME': 'cltbld', 05:57:13 INFO - 'MAIL': '/var/mail/cltbld', 05:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:13 INFO - 'MOZ_NO_REMOTE': '1', 05:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:13 INFO - 'NO_EM_RESTART': '1', 05:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:13 INFO - 'PWD': '/builds/slave/test', 05:57:13 INFO - 'SHELL': '/bin/bash', 05:57:13 INFO - 'SHLVL': '1', 05:57:13 INFO - 'TERM': 'linux', 05:57:13 INFO - 'TMOUT': '86400', 05:57:13 INFO - 'USER': 'cltbld', 05:57:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:13 INFO - '_': '/tools/buildbot/bin/python'} 05:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:13 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:57:13 INFO - Cleaning up... 05:57:13 INFO - Return code: 0 05:57:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:57:13 INFO - Using env: {'DISPLAY': ':0', 05:57:13 INFO - 'HOME': '/home/cltbld', 05:57:13 INFO - 'LANG': 'en_US.UTF-8', 05:57:13 INFO - 'LANGUAGE': 'en_US:en', 05:57:13 INFO - 'LOGNAME': 'cltbld', 05:57:13 INFO - 'MAIL': '/var/mail/cltbld', 05:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:13 INFO - 'MOZ_NO_REMOTE': '1', 05:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:13 INFO - 'NO_EM_RESTART': '1', 05:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:13 INFO - 'PWD': '/builds/slave/test', 05:57:13 INFO - 'SHELL': '/bin/bash', 05:57:13 INFO - 'SHLVL': '1', 05:57:13 INFO - 'TERM': 'linux', 05:57:13 INFO - 'TMOUT': '86400', 05:57:13 INFO - 'USER': 'cltbld', 05:57:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:13 INFO - '_': '/tools/buildbot/bin/python'} 05:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:13 INFO - Downloading/unpacking psutil>=3.1.1 05:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:16 INFO - Installing collected packages: psutil 05:57:16 INFO - Running setup.py install for psutil 05:57:16 INFO - building 'psutil._psutil_linux' extension 05:57:16 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:57:16 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:57:16 INFO - building 'psutil._psutil_posix' extension 05:57:16 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:57:16 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:57:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:16 INFO - Successfully installed psutil 05:57:16 INFO - Cleaning up... 05:57:16 INFO - Return code: 0 05:57:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:57:16 INFO - Using env: {'DISPLAY': ':0', 05:57:16 INFO - 'HOME': '/home/cltbld', 05:57:16 INFO - 'LANG': 'en_US.UTF-8', 05:57:16 INFO - 'LANGUAGE': 'en_US:en', 05:57:16 INFO - 'LOGNAME': 'cltbld', 05:57:16 INFO - 'MAIL': '/var/mail/cltbld', 05:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:16 INFO - 'MOZ_NO_REMOTE': '1', 05:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:16 INFO - 'NO_EM_RESTART': '1', 05:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:16 INFO - 'PWD': '/builds/slave/test', 05:57:16 INFO - 'SHELL': '/bin/bash', 05:57:16 INFO - 'SHLVL': '1', 05:57:16 INFO - 'TERM': 'linux', 05:57:16 INFO - 'TMOUT': '86400', 05:57:16 INFO - 'USER': 'cltbld', 05:57:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:16 INFO - '_': '/tools/buildbot/bin/python'} 05:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:17 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:57:17 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:57:17 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:57:17 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:57:17 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:57:17 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:57:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:57:19 INFO - Installing collected packages: mozsystemmonitor 05:57:19 INFO - Running setup.py install for mozsystemmonitor 05:57:19 INFO - Successfully installed mozsystemmonitor 05:57:19 INFO - Cleaning up... 05:57:19 INFO - Return code: 0 05:57:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:57: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:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:19 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 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:19 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:57:19 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:57:19 INFO - Using env: {'DISPLAY': ':0', 05:57:19 INFO - 'HOME': '/home/cltbld', 05:57:19 INFO - 'LANG': 'en_US.UTF-8', 05:57:19 INFO - 'LANGUAGE': 'en_US:en', 05:57:19 INFO - 'LOGNAME': 'cltbld', 05:57:19 INFO - 'MAIL': '/var/mail/cltbld', 05:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:19 INFO - 'MOZ_NO_REMOTE': '1', 05:57:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:19 INFO - 'NO_EM_RESTART': '1', 05:57:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:19 INFO - 'PWD': '/builds/slave/test', 05:57:19 INFO - 'SHELL': '/bin/bash', 05:57:19 INFO - 'SHLVL': '1', 05:57:19 INFO - 'TERM': 'linux', 05:57:19 INFO - 'TMOUT': '86400', 05:57:19 INFO - 'USER': 'cltbld', 05:57:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:19 INFO - '_': '/tools/buildbot/bin/python'} 05:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:19 INFO - Downloading/unpacking jsonschema==2.5.1 05:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:57:21 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:57:21 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:57:21 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:57:21 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:57:21 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:57:21 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:57:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:21 INFO - warning: no files found matching '*.txt' 05:57:21 INFO - no previously-included directories found matching 'build' 05:57:21 INFO - no previously-included directories found matching 'dist' 05:57:21 INFO - no previously-included directories found matching '.git*' 05:57:21 INFO - Installing collected packages: jsonschema, functools32 05:57:21 INFO - Running setup.py install for functools32 05:57:21 INFO - warning: no files found matching '*.txt' 05:57:21 INFO - no previously-included directories found matching 'build' 05:57:21 INFO - no previously-included directories found matching 'dist' 05:57:21 INFO - no previously-included directories found matching '.git*' 05:57:21 INFO - Successfully installed jsonschema functools32 05:57:21 INFO - Cleaning up... 05:57:21 INFO - Return code: 0 05:57:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:57:21 INFO - Using env: {'DISPLAY': ':0', 05:57:21 INFO - 'HOME': '/home/cltbld', 05:57:21 INFO - 'LANG': 'en_US.UTF-8', 05:57:21 INFO - 'LANGUAGE': 'en_US:en', 05:57:21 INFO - 'LOGNAME': 'cltbld', 05:57:21 INFO - 'MAIL': '/var/mail/cltbld', 05:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:21 INFO - 'MOZ_NO_REMOTE': '1', 05:57:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:21 INFO - 'NO_EM_RESTART': '1', 05:57:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:21 INFO - 'PWD': '/builds/slave/test', 05:57:21 INFO - 'SHELL': '/bin/bash', 05:57:21 INFO - 'SHLVL': '1', 05:57:21 INFO - 'TERM': 'linux', 05:57:21 INFO - 'TMOUT': '86400', 05:57:21 INFO - 'USER': 'cltbld', 05:57:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:21 INFO - '_': '/tools/buildbot/bin/python'} 05:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:22 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:57:22 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:57:22 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:57:22 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:57:22 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:57:22 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:57:23 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:23 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:57:23 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:57:23 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:57:23 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:57:23 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:57:23 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:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:24 INFO - Downloading docopt-0.6.1.tar.gz 05:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:24 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:24 INFO - Running setup.py install for blobuploader 05:57:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:24 INFO - Running setup.py install for requests 05:57:25 INFO - Running setup.py install for docopt 05:57:25 INFO - Successfully installed blobuploader requests docopt 05:57:25 INFO - Cleaning up... 05:57:25 INFO - Return code: 0 05:57:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:25 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 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:25 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:57:25 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:57:25 INFO - Using env: {'DISPLAY': ':0', 05:57:25 INFO - 'HOME': '/home/cltbld', 05:57:25 INFO - 'LANG': 'en_US.UTF-8', 05:57:25 INFO - 'LANGUAGE': 'en_US:en', 05:57:25 INFO - 'LOGNAME': 'cltbld', 05:57:25 INFO - 'MAIL': '/var/mail/cltbld', 05:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:25 INFO - 'MOZ_NO_REMOTE': '1', 05:57:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:25 INFO - 'NO_EM_RESTART': '1', 05:57:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:25 INFO - 'PWD': '/builds/slave/test', 05:57:25 INFO - 'SHELL': '/bin/bash', 05:57:25 INFO - 'SHLVL': '1', 05:57:25 INFO - 'TERM': 'linux', 05:57:25 INFO - 'TMOUT': '86400', 05:57:25 INFO - 'USER': 'cltbld', 05:57:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:25 INFO - '_': '/tools/buildbot/bin/python'} 05:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:25 INFO - Running setup.py (path:/tmp/pip-jeYWHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:25 INFO - Running setup.py (path:/tmp/pip-sTW5Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:25 INFO - Running setup.py (path:/tmp/pip-kKTe0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:25 INFO - Running setup.py (path:/tmp/pip-5WfEdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:25 INFO - Running setup.py (path:/tmp/pip-SePRPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:25 INFO - Running setup.py (path:/tmp/pip-iHb6Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:25 INFO - Running setup.py (path:/tmp/pip-nQbaIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:26 INFO - Running setup.py (path:/tmp/pip-N4iyZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:26 INFO - Running setup.py (path:/tmp/pip-pqxxy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:26 INFO - Running setup.py (path:/tmp/pip-7nHM2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:26 INFO - Running setup.py (path:/tmp/pip-wzgcSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:26 INFO - Running setup.py (path:/tmp/pip-nJd44t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:26 INFO - Running setup.py (path:/tmp/pip-FsFy10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:26 INFO - Running setup.py (path:/tmp/pip-I3Oh55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:26 INFO - Running setup.py (path:/tmp/pip-bYiXiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:26 INFO - Running setup.py (path:/tmp/pip-9GvwO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:26 INFO - Running setup.py (path:/tmp/pip-mguQ86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:26 INFO - Running setup.py install for manifestparser 05:57:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:26 INFO - Running setup.py install for mozcrash 05:57:26 INFO - Running setup.py install for mozdebug 05:57:27 INFO - Running setup.py install for mozdevice 05:57:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Running setup.py install for mozfile 05:57:27 INFO - Running setup.py install for mozhttpd 05:57:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Running setup.py install for mozinfo 05:57:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Running setup.py install for mozInstall 05:57:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Running setup.py install for mozleak 05:57:27 INFO - Running setup.py install for mozlog 05:57:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Running setup.py install for moznetwork 05:57:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:27 INFO - Running setup.py install for mozprocess 05:57:27 INFO - Running setup.py install for mozprofile 05:57:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Running setup.py install for mozrunner 05:57:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Running setup.py install for mozscreenshot 05:57:28 INFO - Running setup.py install for moztest 05:57:28 INFO - Running setup.py install for mozversion 05:57:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:28 INFO - Cleaning up... 05:57:28 INFO - Return code: 0 05:57:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:28 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 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:28 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:57:28 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:57:28 INFO - Using env: {'DISPLAY': ':0', 05:57:28 INFO - 'HOME': '/home/cltbld', 05:57:28 INFO - 'LANG': 'en_US.UTF-8', 05:57:28 INFO - 'LANGUAGE': 'en_US:en', 05:57:28 INFO - 'LOGNAME': 'cltbld', 05:57:28 INFO - 'MAIL': '/var/mail/cltbld', 05:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:28 INFO - 'MOZ_NO_REMOTE': '1', 05:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:28 INFO - 'NO_EM_RESTART': '1', 05:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:28 INFO - 'PWD': '/builds/slave/test', 05:57:28 INFO - 'SHELL': '/bin/bash', 05:57:28 INFO - 'SHLVL': '1', 05:57:28 INFO - 'TERM': 'linux', 05:57:28 INFO - 'TMOUT': '86400', 05:57:28 INFO - 'USER': 'cltbld', 05:57:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:28 INFO - '_': '/tools/buildbot/bin/python'} 05:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:28 INFO - Running setup.py (path:/tmp/pip-vyBvZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:28 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:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:28 INFO - Running setup.py (path:/tmp/pip-pxs5Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:28 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:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:28 INFO - Running setup.py (path:/tmp/pip-rVGTTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:28 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:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:29 INFO - Running setup.py (path:/tmp/pip-ijT9Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:29 INFO - Running setup.py (path:/tmp/pip-cOnrnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:29 INFO - Running setup.py (path:/tmp/pip-1eFXSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:29 INFO - Running setup.py (path:/tmp/pip-XFFH53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:29 INFO - Running setup.py (path:/tmp/pip-U9zhcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:29 INFO - Running setup.py (path:/tmp/pip-KyaL2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:29 INFO - Running setup.py (path:/tmp/pip-O45NpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:29 INFO - Running setup.py (path:/tmp/pip-50wYWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:29 INFO - Running setup.py (path:/tmp/pip-vnp3zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:29 INFO - Running setup.py (path:/tmp/pip-dqLopc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:29 INFO - Running setup.py (path:/tmp/pip-2gpeGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:29 INFO - Running setup.py (path:/tmp/pip-i_7t05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:29 INFO - Running setup.py (path:/tmp/pip-P7VEH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:29 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:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:29 INFO - Running setup.py (path:/tmp/pip-T3aL66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:29 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:57:29 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:57:29 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:57:29 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:57:29 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:57:30 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:57:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:31 INFO - Downloading blessings-1.6.tar.gz 05:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:31 INFO - Installing collected packages: blessings 05:57:31 INFO - Running setup.py install for blessings 05:57:32 INFO - Successfully installed blessings 05:57:32 INFO - Cleaning up... 05:57:32 INFO - Return code: 0 05:57:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:32 INFO - Reading from file tmpfile_stdout 05:57:32 INFO - Current package versions: 05:57:32 INFO - argparse == 1.2.1 05:57:32 INFO - blessings == 1.6 05:57:32 INFO - blobuploader == 1.2.4 05:57:32 INFO - docopt == 0.6.1 05:57:32 INFO - functools32 == 3.2.3-2 05:57:32 INFO - jsonschema == 2.5.1 05:57:32 INFO - manifestparser == 1.1 05:57:32 INFO - mozInstall == 1.12 05:57:32 INFO - mozcrash == 0.17 05:57:32 INFO - mozdebug == 0.1 05:57:32 INFO - mozdevice == 0.48 05:57:32 INFO - mozfile == 1.2 05:57:32 INFO - mozhttpd == 0.7 05:57:32 INFO - mozinfo == 0.9 05:57:32 INFO - mozleak == 0.1 05:57:32 INFO - mozlog == 3.2 05:57:32 INFO - moznetwork == 0.27 05:57:32 INFO - mozprocess == 0.23 05:57:32 INFO - mozprofile == 0.28 05:57:32 INFO - mozrunner == 6.12 05:57:32 INFO - mozscreenshot == 0.1 05:57:32 INFO - mozsystemmonitor == 0.3 05:57:32 INFO - moztest == 0.7 05:57:32 INFO - mozversion == 1.4 05:57:32 INFO - psutil == 3.1.1 05:57:32 INFO - requests == 1.2.3 05:57:32 INFO - wsgiref == 0.1.2 05:57:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57: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:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:32 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 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57:32 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:57:32 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:57:32 INFO - Using env: {'DISPLAY': ':0', 05:57:32 INFO - 'HOME': '/home/cltbld', 05:57:32 INFO - 'LANG': 'en_US.UTF-8', 05:57:32 INFO - 'LANGUAGE': 'en_US:en', 05:57:32 INFO - 'LOGNAME': 'cltbld', 05:57:32 INFO - 'MAIL': '/var/mail/cltbld', 05:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:32 INFO - 'MOZ_NO_REMOTE': '1', 05:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:32 INFO - 'NO_EM_RESTART': '1', 05:57:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:32 INFO - 'PWD': '/builds/slave/test', 05:57:32 INFO - 'SHELL': '/bin/bash', 05:57:32 INFO - 'SHLVL': '1', 05:57:32 INFO - 'TERM': 'linux', 05:57:32 INFO - 'TMOUT': '86400', 05:57:32 INFO - 'USER': 'cltbld', 05:57:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:32 INFO - '_': '/tools/buildbot/bin/python'} 05:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 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:57:32 INFO - Cleaning up... 05:57:32 INFO - Return code: 0 05:57:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57: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:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f07030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, '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': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', '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:57: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'] in /builds/slave/test/build 05:57: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 05:57:32 INFO - Using env: {'DISPLAY': ':0', 05:57:32 INFO - 'HOME': '/home/cltbld', 05:57:32 INFO - 'LANG': 'en_US.UTF-8', 05:57:32 INFO - 'LANGUAGE': 'en_US:en', 05:57:32 INFO - 'LOGNAME': 'cltbld', 05:57:32 INFO - 'MAIL': '/var/mail/cltbld', 05:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:32 INFO - 'MOZ_NO_REMOTE': '1', 05:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:32 INFO - 'NO_EM_RESTART': '1', 05:57:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:32 INFO - 'PWD': '/builds/slave/test', 05:57:32 INFO - 'SHELL': '/bin/bash', 05:57:32 INFO - 'SHLVL': '1', 05:57:32 INFO - 'TERM': 'linux', 05:57:32 INFO - 'TMOUT': '86400', 05:57:32 INFO - 'USER': 'cltbld', 05:57:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:32 INFO - '_': '/tools/buildbot/bin/python'} 05:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:32 INFO - Cleaning up... 05:57:32 INFO - Return code: 0 05:57:32 INFO - Running post-action listener: _resource_record_post_action 05:57:32 INFO - Running post-action listener: _start_resource_monitoring 05:57:32 INFO - Starting resource monitoring. 05:57:32 INFO - ##### 05:57:32 INFO - ##### Finished create-virtualenv step (success) 05:57:32 INFO - ##### 05:57:32 INFO - ##### 05:57:32 INFO - ##### Running install step. 05:57:32 INFO - ##### 05:57:32 INFO - Running pre-action listener: _resource_record_pre_action 05:57:32 INFO - Running main action method: install 05:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:33 INFO - Reading from file tmpfile_stdout 05:57:33 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:33 INFO - Reading from file tmpfile_stdout 05:57:33 INFO - Output received: 05:57:33 INFO - Usage: mozinstall [options] installer 05:57:33 INFO - Options: 05:57:33 INFO - -h, --help show this help message and exit 05:57:33 INFO - -d DEST, --destination=DEST 05:57:33 INFO - Directory to install application into. [default: 05:57:33 INFO - "/builds/slave/test"] 05:57:33 INFO - --app=APP Application being installed. [default: firefox] 05:57:33 INFO - mkdir: /builds/slave/test/build/application 05:57:33 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:57:33 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:57:50 INFO - Reading from file tmpfile_stdout 05:57:50 INFO - Output received: 05:57:50 INFO - /builds/slave/test/build/application/firefox/firefox 05:57:50 INFO - Running post-action listener: _resource_record_post_action 05:57:50 INFO - ##### 05:57:50 INFO - ##### Finished install step (success) 05:57:50 INFO - ##### 05:57:50 INFO - ##### 05:57:50 INFO - ##### Running run-tests step. 05:57:50 INFO - ##### 05:57:50 INFO - Running pre-action listener: _resource_record_pre_action 05:57:50 INFO - Running main action method: run_tests 05:57:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:57:50 INFO - Python 2.7.3 05:57:50 INFO - Return code: 0 05:57:50 INFO - grabbing minidump binary from tooltool 05:57:50 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:57:50 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 0x21c63b0>, '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 0x21c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:57:50 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:57:50 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:57:50 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:57:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:57:50 INFO - Return code: 0 05:57:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:57:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:57:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:57:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:57:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:57:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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:57:50 INFO - Using env: {'DISPLAY': ':0', 05:57:50 INFO - 'HOME': '/home/cltbld', 05:57:50 INFO - 'LANG': 'en_US.UTF-8', 05:57:50 INFO - 'LANGUAGE': 'en_US:en', 05:57:50 INFO - 'LOGNAME': 'cltbld', 05:57:50 INFO - 'MAIL': '/var/mail/cltbld', 05:57:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:57:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:50 INFO - 'MOZ_NO_REMOTE': '1', 05:57:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:50 INFO - 'NO_EM_RESTART': '1', 05:57:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:50 INFO - 'PWD': '/builds/slave/test', 05:57:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:57:50 INFO - 'SHELL': '/bin/bash', 05:57:50 INFO - 'SHLVL': '1', 05:57:50 INFO - 'TERM': 'linux', 05:57:50 INFO - 'TMOUT': '86400', 05:57:50 INFO - 'USER': 'cltbld', 05:57:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097', 05:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:50 INFO - '_': '/tools/buildbot/bin/python'} 05:57:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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:57:50 INFO - mozversion application_buildid: 20160712025529 05:57:50 INFO - mozversion application_changeset: 940674ad1be3c211cc10d18666e3e4705e301f32 05:57:50 INFO - mozversion application_display_name: Nightly 05:57:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:57:50 INFO - mozversion application_name: Firefox 05:57:50 INFO - mozversion application_remotingname: firefox 05:57:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:57:50 INFO - mozversion application_vendor: Mozilla 05:57:50 INFO - mozversion application_version: 50.0a1 05:57:50 INFO - mozversion platform_buildid: 20160712025529 05:57:50 INFO - mozversion platform_changeset: 940674ad1be3c211cc10d18666e3e4705e301f32 05:57:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:57:50 INFO - mozversion platform_version: 50.0a1 05:57:50 INFO - using testdate: 1468328270 05:57:50 INFO - actual date: 1468328270 05:57:50 INFO - starting webserver on 'localhost:41969' 05:57:50 INFO - SUITE-START | Running 2 tests 05:57:50 INFO - TEST-START | dromaeo_css 05:57:50 INFO - operating with platform_type : linux_ 05:57:50 INFO - Initialising browser for dromaeo_css test... 05:57:50 INFO - TEST-INFO | started process 7802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRFXe7T/profile http://localhost:41969/getInfo.html) 05:57:54 INFO - PROCESS | 7802 | __metrics Screen width/height:1600/1200 05:57:54 INFO - PROCESS | 7802 | colorDepth:24 05:57:54 INFO - PROCESS | 7802 | Browser inner width/height: 1024/697 05:57:54 INFO - PROCESS | 7802 | __metrics 05:57:55 INFO - PROCESS | 7802 | 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:57:55 INFO - PROCESS | 7802 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:56 INFO - TEST-INFO | 7802: exit 0 05:57:56 INFO - Browser initialized. 05:57:56 INFO - Running cycle 1/1 for dromaeo_css test... 05:57:56 INFO - TEST-INFO | started process 7886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRFXe7T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:07 INFO - PROCESS | 7886 | RSS: Main: 158023680 05:58:07 INFO - PROCESS | 7886 | 05:58:59 INFO - PROCESS | 7886 | 1468328339192 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] 06:01:07 INFO - PROCESS | 7886 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41969/tests/dromaeo/cssquery-ext.html) 06:01:08 INFO - PROCESS | 7886 | RSS: Main: 258797568 06:01:08 INFO - PROCESS | 7886 | 06:04:10 INFO - PROCESS | 7886 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-ext.html (next: http://localhost:41969/tests/dromaeo/cssquery-jquery.html) 06:04:10 INFO - PROCESS | 7886 | RSS: Main: 239292416 06:04:10 INFO - PROCESS | 7886 | 06:07:12 INFO - PROCESS | 7886 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41969/tests/dromaeo/cssquery-mootools.html) 06:07:13 INFO - PROCESS | 7886 | RSS: Main: 244322304 06:07:13 INFO - PROCESS | 7886 | 06:10:15 INFO - PROCESS | 7886 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41969/tests/dromaeo/cssquery-prototype.html) 06:10:16 INFO - PROCESS | 7886 | RSS: Main: 234172416 06:10:16 INFO - PROCESS | 7886 | 06:13:18 INFO - PROCESS | 7886 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41969/tests/dromaeo/cssquery-yui.html) 06:13:19 INFO - PROCESS | 7886 | RSS: Main: 210022400 06:13:19 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-yui.html (next: http://localhost:41969/tests/dromaeo/cssquery-dojo.html) 06:16:22 INFO - PROCESS | 7886 | RSS: Main: 289587200 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | __start_tp_report 06:16:22 INFO - PROCESS | 7886 | _x_x_mozilla_page_load 06:16:22 INFO - PROCESS | 7886 | _x_x_mozilla_page_load_details 06:16:22 INFO - PROCESS | 7886 | |i|pagename|runs| 06:16:22 INFO - PROCESS | 7886 | |0;dojo.html;3056.449839028975;3061.280599059219;3091.1962889648685;3096.2723759916416;3102.751779857612;5182.170852663565;5224.45143259958;5319.78720851166;5353.0899747042995;5380.704061276633;5318.973405132969;5322.787088516465;5325.147976323789;5386;5391.946080539194;4780.1873681474235;4784.784684689189;4839.153148199051;4849.199882019462;5020.146575082226;4889.853304400874;4901.754912254391;4911.45973942866;4959.578435832958;4976.975115124419;4780.163471392492;4838.830640927586;4841.7579121044155;4844.660873738822;4848.97575512122;4823.493533179036;4901.779419926076;4905.068037072981;4915.631327650441;4935.80256789728;4697.459792123916;4797.52024797521;4891.706497610119;4894.461609222983;4928.137575924235;5339.065663508871;5370.4361042090795;5390.299261096072;5391.326084239431;5401.162819762943;5713.771449142071;5717.742701578439;5731.369549349568;5741.511971482429;5746.367899531048;5502.972485137589;5723;5744.253247077895;5794.478496935254;5832.737526811304;5391.191321301758;5430.321209848769;5455.427180146067;5458.044842152568;5459.3994660587305;5967.1347654589845;5967.343592204854;5967.403259673998;5968.104784282393;5969.671668058299;5407.486288802557;5521.089020311684;5529.364123125812;5572.275604171513;5700.429957004267;5709.571782116358;5732.627379220337;5737.426257374313;5739.225204597409;5756.798512052058;5686.289213848269;5691;5694.715264236855;5698.230738850285;5729.0260655688635;5502.8349149525575;5632.436756324417;5645.887082258414;5675.943240567624;5721.256236689203;4404.889877753018;4407.6473882089995;4408.889777755518;4410.272305069691;4443.844465443694;3913.9217215655526;3942.073612701013;3942.231264903374;3958.44581758554;3963.504561929759;5402.810901618424;5413;5448.210009548593;5488.25908502355;5528.695921724263;5700.515456186187;5732.570057245724;5743.4256574342235;5767.279090113736;5790.42095790426;5316.202569614511;5404.648697834628;5453;5470.8085217017215;5471.480209380182;4865.6107511399005;4924.975375123101;4934.259861020875;4946.307516947631;4952;6017.578769486182;6019.067044608093;6019.367966363557;6020.789272375446;6032.426919442733;5586.301712285965;5731.770729170787;5794.5364370851075;5796.333421656394;5810.128480727924;5988.730507127277;6051.062085376773;6052.969735151296;6058.909116363347;6120.234970628671;5676.545876329967;5702.059160238514;5707.743151558107;5727.427257274406;5729.369769325454;5565.721713914207;5693.914591281051;5743.971280143573;5812.128180772918;5817.505512031525;5674.375818659862;5677.148427735872;5680.71596420169;5681.034224181982;5720.885582288248;5595.8880822384135;5645.71771411436;5646.85882852932;5647.463490968352;5657.660540367591;5445.727713614383;5556;5629.099344104923;5639.8590035249445;5645.237892884408;5645.266115404971;5650.717464126859;5653.575981801464;5703.230063941481;5708.8572785679025;5428.348598168245;5459.399466058651;5477.233187353694;5477.260569823182;5493.395726470005 06:16:22 INFO - PROCESS | 7886 | |1;ext.html;592.2360155399534;621.9626822390657;627.9748810047597;637.0539748473519;642.5277421095494;32550.511742323855;43225;43495.99999999992;43545;47754;46090.76954615222;46290.30564541535;46474.535254647446;46489.99999999991;46517.767411162895;8808.295336373074;8922.955385223097;8951.910480895205;8958;8962.283017358628;8837.248833849098;8934.10658934108;8944.15030572098;8944.552772361389;8949.373543851932;8954.731358059269;8991.370604057718;8992.68525601604;8995.820083598324;9003.459792412443;8915.68795092172;8942.731718048437;8954.149355811187;8959.5968181432;8960.372773905829;8832.205101540892;8875.600597973042;8889.777755556099;8893;8896.510691911943;43885.56114438878;43968;44013.559864401264;44334.55665443336;44343.77828110839;3486.6513348665185;3577.0342007657787;3605.6394360563995;3704.2776658551593;3795.772253664789;31292.687073129433;33973.32053358919;34114;34127.48808767871;34253;4497.302918047708;4527.524609915944;4533.297338912441;4550.158220729142;4595.126925884105;369648.42812575196;369869;370056;371912.9999999973;372887;61301.38698613046;61382.38617613856;61698.457538561895;61784;61824.38175618276;57750.7112464435;63525.36474635287;64105.35894641087;65033;65198;17322.307107715802;17476.650466990843;17490.825091749175;17497.56256093608;17498.212580433912;13312.06815522904;13404.128731632412;13404.262765547794;13406.262655554037;13418.798718019238;17249;18784.154713037948;18787.15457804402;19078.332258371167;19767.802321976884;7071.717131314793;7073.6913670971035;7088.184858741208;7405.666744996488;7429.777106686808;982.8378317577663;1088.5972190289485;1102.250469680625;1157.3808012713157;1161.5702190189684;33942.15144621404;34069.48895766566;34235;34297.31405371879;34697.479537806954;1117.7987962166726;1134.4157758754247;1134.6822889590928;1203.4404002139047;1224.2593231095236;17152.056636885092;17536.736948945778;17665.38171164003;17744.645107097786;17826.5543361417;581045;593024;594675;594691;597673;60540.881069162366;60842.391576084556;60919;61043;61086.69456652688;1249.7063190150307;1291.0575280045432;1362.8228330316995;1371.6502291915697;1391.8538553451945;30484.542731859485;35325.07248103566;35624.64375356213;35680;35805.56777728862;51452.48547514477;51724;51974.99999999849;52047.73976130095;52050;338731;340258;340413;341109;341293;28329.858350708113;29246;29343.559846601893;29389;29406;49870.75064624799;51230;51666.48333516767;52325;52327;42339;42937.35593966155;43426.782866085465;43708;44871.77564112159;42254.1549169021;42549.36175957425;42598.361024585276;42790.786046069574;43115.56884431116;42578.5742142587;42757.1448571033;43165;43468.34797477977;43598.782006089765;37323.626763733104;38212;38569.6143038566;39099.80450097731;39170.80414597909;29097.709022910345;29321.413571728874;29335;29546.55680164842;29728.554071689374 06:16:22 INFO - PROCESS | 7886 | |2;jquery.html;376.2418726266575;397.23333965446693;398.20955403524;402.18156052433295;418.09482470451474;14388.640283992892;19146.32987845424;19214.231430742755;20153.496162595944;20359.69460458094;19134.71297930532;19211.32760353388;19951.40145795625;20030.399088027345;20052.298169564066;5772.595918285721;5812.82561523155;5929.703514824263;5930.347661757203;5991.2510936132985;5695.14572814077;5832.183494310779;5862.091375836752;5875.559333050017;5907.995640741085;5747.166660834177;5789.884202315951;5800;5901.763929442838;5905.43898329658;5845.766169353221;5907.88184236315;5910.645361278337;5913.704314784265;5944;5129.769160387791;5196.662216955886;5221.6083793715625;5229.607779416559;5241.371035475768;22175.334739957667;22279;22933.312000640006;23193.76806231933;23214;2256.4810093678384;2381.2380038387723;2446.0704932125855;2569.2252367443116;3092.8762849485993;10468.633597824115;10544.314619549705;10706.518206680717;10767.730806729816;10820.458977051116;1993.8604297699167;2006.1173083843062;2031.5632139090167;2052.5997430501056;2058.8661736987196;70507.64746176236;83595.16404835995;86880.13119868784;87196.12803872007;87712.56143719304;10824.13406927444;10955.726106847393;10990.725231869268;11245.003847967928;11292.209545331913;15595.532134035997;15603.765943510856;15623.062616243062;15634.296456659478;15703.371865125495;7985.441019128605;8079.046212699334;8136.389770767216;8379.455335403301;8435.620397082146;7722.92277077222;7781.416393770377;7873.881891771628;7907.446478746548;7996.520208787491;4886.92669609949;4909.189983652657;4909.410870695539;4910.74775932135;4967.801287948441;1971.5465442948203;2002.469345623411;2026.696484729881;2030.502526880894;2055.0649454498166;928.5774972387547;1020.6886899495726;1040.6461802987021;1062.1821197677746;1111.3165403276926;19755;20157.899210504147;20238.59522809565;20243.595128097357;20511.282105126247;981.9803603927882;1008.3949630221955;1054.6625079974335;1074.2694967422071;1083.34999000599;10860.294080884718;11429.371384573762;11548.480318385693;11571;11586.304821710555;409552;446066;470487;486485;490244;72147.63926180336;97421.51289243509;97730;97758;98045;1005.8491226315965;1025.4001409175717;1035.0684384054412;1042.2339580408393;1047.382044593678;17662.116894154984;17680.82319176792;18183.45449636513;18213.817861821743;18535.165917533475;40201;42458.57541424546;42633.147337053706;42679.3598096035;42960;56479.435205647416;56889;57892.55268618318;63002.68498657477;66537.66731166313;8665.053881680258;8679.86980195285;8697.869531957153;9003.909960900308;9123.27013838878;10466.895331046591;13590.660233494242;14223.715525689635;14319.212443315717;14728.410863565337;12001.1599188056;12143.817842732542;12285.447154878231;12813.93593032029;12926.741465170831;12050.879491205327;12287.324197169242;12417.813732794197;12531.74936501283;12673.873261267268;11977.520899164285;12529.37353132358;12530.874691253335;12549.309787961749;12621.242725436503;8763.3427492937;8836.734897953073;8925.553722313989;8998.055204203727;9008.954955225181;9083.500407477652;11864.940675296568;12496.500139994298;12788.168769029984;12908.160969537326 06:16:22 INFO - PROCESS | 7886 | |3;mootools.html;1351.459416233507;1494.5815171751906;1521.3458212968424;1655.2137734576079;1813.8548916086716;8475.70335038273;8827.073157318486;8831.161039701234;8996.95501522493;9075.092490750922;8779.165979231962;8997.59510822013;9000.729978100651;9008.369414141012;9021.864672029924;1945.5816999345138;2194.429448343434;2225.6772767948637;2271.5116250006245;2271.943201419961;2312.4103353644796;2323.4191452136965;2323.93028209154;2324.976750232501;2326.4765427778784;2250.5723912456583;2256.7968882800546;2285.9771402286015;2285.988570057147;2286.942826429336;1863.9347622833272;1921.6541022615922;1930.7393501877207;2167.2522979572022;2212.0819859758262;2201.0975500045065;2235.407616981501;2235.407616981501;2238.2837492002564;2239.261043855524;8912.420692654956;8955.865662015074;8965.282777377794;8968.865467018;9014.278857691435;5029.949700502984;5129.230615407681;5138.049460849755;5139.15203991341;5144.356955380577;3147.653758086609;3223.226425657825;3277.246233366315;3277.9344413111844;3278.672132786702;976.6239997600903;1011.8532812742185;1028.511457057899;1031;1043.3879655931603;12332.445039973223;12352.320622365767;12365.07261955357;12370.381480926007;12379.75240495185;4371.912561748747;4490.595846373842;4586.1057093866375;4628.768561571908;4630.073985202973;10294.073533381881;10352.844707329396;10380.169586433067;10407.271490995709;10408.47957602117;5281.287026251407;5293.285406470077;5295.470452954673;5299.947000529945;5300.072487314705;4100.712950093524;4693.06138772247;4816.253480710496;4819.879503012453;4890.997345544155;3963.6630886374983;3991.341428664238;4039.636432721069;4078;4108.609682080198;1115.1079136690614;1202.4829323391025;1244.6577191272472;1255.0649765924263;1258.7482503499336;886.3751055505853;898.9235914947172;914.1041779056562;921.6221349246777;921.6589861751195;6036.105386009818;6087.178230938855;6224.470919971763;6231.158793562901;6255.624662520263;963.4315753705348;964.1852634523816;985.7387792235063;998.1715176403568;1009.8232809258351;3934.2918274710255;4098.713090083726;4109.527404348479;4142.482189726285;4164.937525937114;11230;11513.88486115145;11559.248648837798;11575.42122893852;11576.073914086854;11390.601328953613;11456.427178641035;11474.311541307548;11495.942520287512;11496.885031149748;7985.161558036322;8330.375221858385;8330.750077497685;8430.831383372299;8569.057403685589;5819.738111785065;5856.121581762599;5882.852928676817;5960.225170727777;6200.286966998853;10308.690739277834;10309.22680798928;10408.230600798033;10429.9478502607;10429.947850261004;10041.49792510357;10206.438645874548;10543.26197166191;10592.841107383549;10592.947035264775;8024.638891250024;8085.272325490734;8183;8204.712835050745;8214.4660597061;8589.871151932852;8594.140585941355;8651.783705407415;8715.259202967818;8795.868061978947;6290;7826;7920.485168464032;8085.716999904975;8287.461315014505;7748.108967468813;7820.843583128191;7876.8818467721885;8047.798805029921;8065.758027259191;7316.634168291671;7333.816654583678;7383.96624472585;7391.519551229153;7416.0729908761405;5243.947560524346;5260.500252476008;5278.392984806796;5300.337457817773;5329.040772660803;5051.62112841531;5216.40011398694;5216.556592689664;5315;5409.8377048687025 06:16:22 INFO - PROCESS | 7886 | |4;prototype.html;324.06184097039073;336.4734190991099;339.49245877413256;341.2696828786397;341.38720995812514;5050.292958985745;5214.687118772871;5230.686158830473;5264.973675131624;5284.497972692598;5232.3459567554055;5250.789968401269;5261.842144735654;5269.130593452076;5269.920951185725;4132.235536425764;4135.875923722293;4150.97924510377;4158.6465150462245;4176.540580536139;3971.8212680429306;3986.70099742519;4153.314703073989;4179.895502612444;4188.81150348235;4090;4113.650339721113;4114.547399786021;4115.012397024722;4119.8970025749295;4102.712810103294;4104.507459104897;4105.178964207155;4109.794510274504;4138.668906487474;3458.602260739997;3645.453182022691;3649.178934739689;3664.5236119304586;3672.8714494992546;5119.667221630582;5133.845984620468;5135.974320128413;5141.460146684619;5150.253213284091;4679.719216847;4693.272542755844;4699.801550604574;4700.7179569225955;4708.375610414436;5318.165232994611;5373.596980226499;5459.481349271852;5475.589330800206;5475.863103422406;3927.9410808837893;3938.090026337224;3956.149427873021;3957.1294315250598;3971.62269584389;5666.263385759866;5695.77216911323;5702.65784052954;5702.914456283159;5716.742746576372;3966.087799806061;4044.1102957349335;4055.1889622075114;4058.817353219112;4070.1249231415386;4028.7179897407486;4103.363978583325;4104.486939132608;4106.178764247163;4109.856155034559;4621.92641893144;4657.510961349009;4658.091672123904;4660.555227879368;4674.415698037746;5461.371942226615;5503.642263252876;5506.697131657717;5506.724663766796;5512.3385193777;1278.673938145767;1313.3039489070807;1400.649837540615;1407.8451370349253;1410.8024876517295;957.6360982826482;958.9856152157723;972.1202311907734;980.8822941247096;987.244757760314;5351.438098999627;5354.759035843318;5356.437574054668;5366.892662146735;5376.892462150813;5565.220869078333;5572.4148964358155;5588.2455868458055;5595.860103497445;5598.412166722517;5235.240890070919;5375.274337964403;5375.704336261465;5394.217838413408;5493.2034854945805;2997.970020299813;3000.294930691286;3011.5482677598097;3028.6825231024577;3035.7723170762283;5864.560157988168;5878.676672782953;5879.853003674943;5943.31651860033;5948.196993405922;5607.327120745455;5803.912941305884;5809.854753631109;5812.534997200214;5818.76724930998;5820.505257053113;5851.38560451155;5852.590318677651;5883.882322353444;5884.32330282023;5375.408705042364;5679.160419790105;5682.886342273214;5684.971575142098;5691.288588926384;5600.383957764724;5650.3219613646625;5671.829845104653;5674.4325567444575;5677.318721753416;5898.203742494708;5939.613925094855;5947.256592925884;5948.494377977747;5975.342712301556;3928.4286285486032;4005.399190121505;4047.8785636430953;4064.7357921734992;4074.8777536673947;5661.801836935688;5682.346530149086;5699.287589051369;5704.919343554434;5718.199452076796;5479.479449452296;5550.306211723535;5581.609287349846;5616;5619.943800561942;5543.085390910454;5558.777648894161;5561.304836895388;5565.721713914207;5583.720813959205;5491.917621235605;5537.972310138423;5548.611597188158;5567.888642227052;5578.358488773681;5395.730213489388;5491.75287112073;5544.085225937675;5555.3611334697025;5600.187972743848;5384.515393614593;5414.539764119936;5464.18037294409;5472.616916815944;5481.314835645544 06:16:22 INFO - PROCESS | 7886 | |5;yui.html;687.6252442418885;763.9427042971777;765.5253742679539;770.7186876789966;859.6948083430382;15453.381864725412;16774.83225167748;16778.496645100637;16823.41118060868;16845.241964111614;15067.397304107822;15228;15258.160801155933;15259.389624415011;15331.386744530206;659;793.2107552984788;820.9261166495014;821.6590115102225;822.9341652667803;806.8628333183373;813.9918600814004;818.7093581778472;822.48183644304;823.1233736071065;704.6688056613409;873.5195642396689;875.9824803503926;876.6712482818942;880.1506546182932;717.3938022371088;723.0130871360582;814.5031530766228;834.5284914023557;840.260570697782;613.3927411862225;613.7115555688864;613.7575657615262;613.8741557980647;613.8772245550863;15061;15084.773728394082;15099.924500377538;15118.470853520073;15246.237688115662;14593.197374144418;14858.554243372608;14927.179005154821;14970.775438368433;14971.850281497154;12133.150679452441;12327.44526496301;12467.75064498714;13165.86834131656;13585.86414135856;4718.598919091882;4809.350737650408;4816.975915120437;4943.9258411123865;4971.105201063807;78263.21736782673;80087.5995620024;80090;80288;80536.59731701363;11697.356645384585;11773.411329433495;11934.471941442907;11994.160408771477;12155.209911355734;21607.459813504473;21626.567468650854;21640.160586350336;21656.458588535414;21672.13311467555;8958.10418958112;9038.457692538337;9184.954075229582;9240.306976976754;9326.393784403992;4899.240617704261;4949.232868905326;5005.34930459038;5088.643794934319;5131.1277082895995;3429.9314013719586;3554.928901421957;3558.893233203008;3569.268299998494;3571.678548930609;637.5250438423404;642.1235014205575;644.861354808709;653.8463461086641;656.937590928861;725.9310365515267;750.7147284032033;756.5498528375608;777.4946284914738;785.3952456608494;10032.849507257397;10033.247506437046;10379.429131397847;10530.947345263225;10545.156387488982;763.1109757132989;770.5569297653872;780.4887798491997;780.6096951524238;791.4815795653856;5088.592912566985;5167.199084141964;5224.791008359623;5230.34620672416;5233.476652334736;114807.42596286965;117453.41273293579;119857;121068;124381;91148;92148;93582.53208733913;95602;97958;58282.7085864568;72833.6358318205;87814.56092719495;92155;94382;9865.408075515492;10003.749906252402;10007.149392301735;10113.342632728849;10124.898751012395;44003.559964401225;46645.76677116593;47820.760896195294;55117.44882551123;55696;57161.71419142878;59697;77778;78698;80736.19263807287;13448.596542103753;13463.528776492776;13664.453421863014;13697.931510342783;14135.787963180766;14510.927445362706;14643.85356146425;15246.390144394543;15337.15645639456;15349.539513814603;15191.620209494851;15267.160306183267;15457.149856757986;15764.644149518344;15783.763243551126;14247.857521425069;14639.707205856037;14705.632359190678;14716.264186790404;14792.926035369754;14892.702145957237;15196;15212.163331016458;15216.467423640119;15250.466233681767;11037.779244414905;11185.608503702331;11339.376334301693;11379.317240965569;11539;11686.88313116892;11884.643460696192;11925.76148477043;12076.275423474619;12242.326672033123 06:16:22 INFO - PROCESS | 7886 | __end_tp_report 06:16:22 INFO - PROCESS | 7886 | __start_cc_report 06:16:22 INFO - PROCESS | 7886 | _x_x_mozilla_cycle_collect,895 06:16:22 INFO - PROCESS | 7886 | __end_cc_report 06:16:22 INFO - PROCESS | 7886 | __startTimestamp1468329382430__endTimestamp 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | ------- Summary: start ------- 06:16:22 INFO - PROCESS | 7886 | Number of tests: 6 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | [#0] dojo.html Cycles:165 Average:5337.56 Median:5502.97 stddev:614.83 (11.2%) stddev-sans-first:590.10 06:16:22 INFO - PROCESS | 7886 | Values: 3056.4 3061.3 3091.2 3096.3 3102.8 5182.2 5224.5 5319.8 5353.1 5380.7 5319.0 5322.8 5325.1 5386.0 5391.9 4780.2 4784.8 4839.2 4849.2 5020.1 4889.9 4901.8 4911.5 4959.6 4977.0 4780.2 4838.8 4841.8 4844.7 4849.0 4823.5 4901.8 4905.1 4915.6 4935.8 4697.5 4797.5 4891.7 4894.5 4928.1 5339.1 5370.4 5390.3 5391.3 5401.2 5713.8 5717.7 5731.4 5741.5 5746.4 5503.0 5723.0 5744.3 5794.5 5832.7 5391.2 5430.3 5455.4 5458.0 5459.4 5967.1 5967.3 5967.4 5968.1 5969.7 5407.5 5521.1 5529.4 5572.3 5700.4 5709.6 5732.6 5737.4 5739.2 5756.8 5686.3 5691.0 5694.7 5698.2 5729.0 5502.8 5632.4 5645.9 5675.9 5721.3 4404.9 4407.6 4408.9 4410.3 4443.8 3913.9 3942.1 3942.2 3958.4 3963.5 5402.8 5413.0 5448.2 5488.3 5528.7 5700.5 5732.6 5743.4 5767.3 5790.4 5316.2 5404.6 5453.0 5470.8 5471.5 4865.6 4925.0 4934.3 4946.3 4952.0 6017.6 6019.1 6019.4 6020.8 6032.4 5586.3 5731.8 5794.5 5796.3 5810.1 5988.7 6051.1 6053.0 6058.9 6120.2 5676.5 5702.1 5707.7 5727.4 5729.4 5565.7 5693.9 5744.0 5812.1 5817.5 5674.4 5677.1 5680.7 5681.0 5720.9 5595.9 5645.7 5646.9 5647.5 5657.7 5445.7 5556.0 5629.1 5639.9 5645.2 5645.3 5650.7 5653.6 5703.2 5708.9 5428.3 5459.4 5477.2 5477.3 5493.4 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | [#1] ext.html Cycles:180 Average:60696.11 Median:43325.89 stddev:119140.08 (275.0%) stddev-sans-first:119388.84 06:16:22 INFO - PROCESS | 7886 | Values: 592.2 622.0 628.0 637.1 642.5 32550.5 43225.0 43496.0 43545.0 47754.0 46090.8 46290.3 46474.5 46490.0 46517.8 8808.3 8923.0 8951.9 8958.0 8962.3 8837.2 8934.1 8944.2 8944.6 8949.4 8954.7 8991.4 8992.7 8995.8 9003.5 8915.7 8942.7 8954.1 8959.6 8960.4 8832.2 8875.6 8889.8 8893.0 8896.5 43885.6 43968.0 44013.6 44334.6 44343.8 3486.7 3577.0 3605.6 3704.3 3795.8 31292.7 33973.3 34114.0 34127.5 34253.0 4497.3 4527.5 4533.3 4550.2 4595.1 369648.4 369869.0 370056.0 371913.0 372887.0 61301.4 61382.4 61698.5 61784.0 61824.4 57750.7 63525.4 64105.4 65033.0 65198.0 17322.3 17476.7 17490.8 17497.6 17498.2 13312.1 13404.1 13404.3 13406.3 13418.8 17249.0 18784.2 18787.2 19078.3 19767.8 7071.7 7073.7 7088.2 7405.7 7429.8 982.8 1088.6 1102.3 1157.4 1161.6 33942.2 34069.5 34235.0 34297.3 34697.5 1117.8 1134.4 1134.7 1203.4 1224.3 17152.1 17536.7 17665.4 17744.6 17826.6 581045.0 593024.0 594675.0 594691.0 597673.0 60540.9 60842.4 60919.0 61043.0 61086.7 1249.7 1291.1 1362.8 1371.7 1391.9 30484.5 35325.1 35624.6 35680.0 35805.6 51452.5 51724.0 51975.0 52047.7 52050.0 338731.0 340258.0 340413.0 341109.0 341293.0 28329.9 29246.0 29343.6 29389.0 29406.0 49870.8 51230.0 51666.5 52325.0 52327.0 42339.0 42937.4 43426.8 43708.0 44871.8 42254.2 42549.4 42598.4 42790.8 43115.6 42578.6 42757.1 43165.0 43468.3 43598.8 37323.6 38212.0 38569.6 39099.8 39170.8 29097.7 29321.4 29335.0 29546.6 29728.6 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | [#2] jquery.html Cycles:180 Average:28522.96 Median:23063.54 stddev:76346.49 (331.0%) stddev-sans-first:76531.41 06:16:22 INFO - PROCESS | 7886 | Values: 376.2 397.2 398.2 402.2 418.1 14388.6 19146.3 19214.2 20153.5 20359.7 19134.7 19211.3 19951.4 20030.4 20052.3 5772.6 5812.8 5929.7 5930.3 5991.3 5695.1 5832.2 5862.1 5875.6 5908.0 5747.2 5789.9 5800.0 5901.8 5905.4 5845.8 5907.9 5910.6 5913.7 5944.0 5129.8 5196.7 5221.6 5229.6 5241.4 22175.3 22279.0 22933.3 23193.8 23214.0 2256.5 2381.2 2446.1 2569.2 3092.9 10468.6 10544.3 10706.5 10767.7 10820.5 1993.9 2006.1 2031.6 2052.6 2058.9 70507.6 83595.2 86880.1 87196.1 87712.6 10824.1 10955.7 10990.7 11245.0 11292.2 15595.5 15603.8 15623.1 15634.3 15703.4 7985.4 8079.0 8136.4 8379.5 8435.6 7722.9 7781.4 7873.9 7907.4 7996.5 4886.9 4909.2 4909.4 4910.7 4967.8 1971.5 2002.5 2026.7 2030.5 2055.1 928.6 1020.7 1040.6 1062.2 1111.3 19755.0 20157.9 20238.6 20243.6 20511.3 982.0 1008.4 1054.7 1074.3 1083.3 10860.3 11429.4 11548.5 11571.0 11586.3 409552.0 446066.0 470487.0 486485.0 490244.0 72147.6 97421.5 97730.0 97758.0 98045.0 1005.8 1025.4 1035.1 1042.2 1047.4 17662.1 17680.8 18183.5 18213.8 18535.2 40201.0 42458.6 42633.1 42679.4 42960.0 56479.4 56889.0 57892.6 63002.7 66537.7 8665.1 8679.9 8697.9 9003.9 9123.3 10466.9 13590.7 14223.7 14319.2 14728.4 12001.2 12143.8 12285.4 12813.9 12926.7 12050.9 12287.3 12417.8 12531.7 12673.9 11977.5 12529.4 12530.9 12549.3 12621.2 8763.3 8836.7 8925.6 8998.1 9009.0 9083.5 11864.9 12496.5 12788.2 12908.2 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | [#3] mootools.html Cycles:180 Average:5879.51 Median:4661.57 stddev:3431.07 (73.6%) stddev-sans-first:3423.82 06:16:22 INFO - PROCESS | 7886 | Values: 1351.5 1494.6 1521.3 1655.2 1813.9 8475.7 8827.1 8831.2 8997.0 9075.1 8779.2 8997.6 9000.7 9008.4 9021.9 1945.6 2194.4 2225.7 2271.5 2271.9 2312.4 2323.4 2323.9 2325.0 2326.5 2250.6 2256.8 2286.0 2286.0 2286.9 1863.9 1921.7 1930.7 2167.3 2212.1 2201.1 2235.4 2235.4 2238.3 2239.3 8912.4 8955.9 8965.3 8968.9 9014.3 5029.9 5129.2 5138.0 5139.2 5144.4 3147.7 3223.2 3277.2 3277.9 3278.7 976.6 1011.9 1028.5 1031.0 1043.4 12332.4 12352.3 12365.1 12370.4 12379.8 4371.9 4490.6 4586.1 4628.8 4630.1 10294.1 10352.8 10380.2 10407.3 10408.5 5281.3 5293.3 5295.5 5299.9 5300.1 4100.7 4693.1 4816.3 4819.9 4891.0 3963.7 3991.3 4039.6 4078.0 4108.6 1115.1 1202.5 1244.7 1255.1 1258.7 886.4 898.9 914.1 921.6 921.7 6036.1 6087.2 6224.5 6231.2 6255.6 963.4 964.2 985.7 998.2 1009.8 3934.3 4098.7 4109.5 4142.5 4164.9 11230.0 11513.9 11559.2 11575.4 11576.1 11390.6 11456.4 11474.3 11495.9 11496.9 7985.2 8330.4 8330.8 8430.8 8569.1 5819.7 5856.1 5882.9 5960.2 6200.3 10308.7 10309.2 10408.2 10429.9 10429.9 10041.5 10206.4 10543.3 10592.8 10592.9 8024.6 8085.3 8183.0 8204.7 8214.5 8589.9 8594.1 8651.8 8715.3 8795.9 6290.0 7826.0 7920.5 8085.7 8287.5 7748.1 7820.8 7876.9 8047.8 8065.8 7316.6 7333.8 7384.0 7391.5 7416.1 5243.9 5260.5 5278.4 5300.3 5329.0 5051.6 5216.4 5216.6 5315.0 5409.8 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | [#4] prototype.html Cycles:180 Average:4662.44 Median:5370.24 stddev:1373.70 (25.6%) stddev-sans-first:1338.40 06:16:22 INFO - PROCESS | 7886 | Values: 324.1 336.5 339.5 341.3 341.4 5050.3 5214.7 5230.7 5265.0 5284.5 5232.3 5250.8 5261.8 5269.1 5269.9 4132.2 4135.9 4151.0 4158.6 4176.5 3971.8 3986.7 4153.3 4179.9 4188.8 4090.0 4113.7 4114.5 4115.0 4119.9 4102.7 4104.5 4105.2 4109.8 4138.7 3458.6 3645.5 3649.2 3664.5 3672.9 5119.7 5133.8 5136.0 5141.5 5150.3 4679.7 4693.3 4699.8 4700.7 4708.4 5318.2 5373.6 5459.5 5475.6 5475.9 3927.9 3938.1 3956.1 3957.1 3971.6 5666.3 5695.8 5702.7 5702.9 5716.7 3966.1 4044.1 4055.2 4058.8 4070.1 4028.7 4103.4 4104.5 4106.2 4109.9 4621.9 4657.5 4658.1 4660.6 4674.4 5461.4 5503.6 5506.7 5506.7 5512.3 1278.7 1313.3 1400.6 1407.8 1410.8 957.6 959.0 972.1 980.9 987.2 5351.4 5354.8 5356.4 5366.9 5376.9 5565.2 5572.4 5588.2 5595.9 5598.4 5235.2 5375.3 5375.7 5394.2 5493.2 2998.0 3000.3 3011.5 3028.7 3035.8 5864.6 5878.7 5879.9 5943.3 5948.2 5607.3 5803.9 5809.9 5812.5 5818.8 5820.5 5851.4 5852.6 5883.9 5884.3 5375.4 5679.2 5682.9 5685.0 5691.3 5600.4 5650.3 5671.8 5674.4 5677.3 5898.2 5939.6 5947.3 5948.5 5975.3 3928.4 4005.4 4047.9 4064.7 4074.9 5661.8 5682.3 5699.3 5704.9 5718.2 5479.5 5550.3 5581.6 5616.0 5619.9 5543.1 5558.8 5561.3 5565.7 5583.7 5491.9 5538.0 5548.6 5567.9 5578.4 5395.7 5491.8 5544.1 5555.4 5600.2 5384.5 5414.5 5464.2 5472.6 5481.3 06:16:22 INFO - PROCESS | 7886 | 06:16:22 INFO - PROCESS | 7886 | [#5] yui.html Cycles:180 Average:20981.87 Median:25682.18 stddev:29685.97 (115.6%) stddev-sans-first:29730.13 06:16:22 INFO - PROCESS | 7886 | Values: 687.6 763.9 765.5 770.7 859.7 15453.4 16774.8 16778.5 16823.4 16845.2 15067.4 15228.0 15258.2 15259.4 15331.4 659.0 793.2 820.9 821.7 822.9 806.9 814.0 818.7 822.5 823.1 704.7 873.5 876.0 876.7 880.2 717.4 723.0 814.5 834.5 840.3 613.4 613.7 613.8 613.9 613.9 15061.0 15084.8 15099.9 15118.5 15246.2 14593.2 14858.6 14927.2 14970.8 14971.9 12133.2 12327.4 12467.8 13165.9 13585.9 4718.6 4809.4 4817.0 4943.9 4971.1 78263.2 80087.6 80090.0 80288.0 80536.6 11697.4 11773.4 11934.5 11994.2 12155.2 21607.5 21626.6 21640.2 21656.5 21672.1 8958.1 9038.5 9185.0 9240.3 9326.4 4899.2 4949.2 5005.3 5088.6 5131.1 3429.9 3554.9 3558.9 3569.3 3571.7 637.5 642.1 644.9 653.8 656.9 725.9 750.7 756.5 777.5 785.4 10032.8 10033.2 10379.4 10530.9 10545.2 763.1 770.6 780.5 780.6 791.5 5088.6 5167.2 5224.8 5230.3 5233.5 114807.4 117453.4 119857.0 121068.0 124381.0 91148.0 92148.0 93582.5 95602.0 97958.0 58282.7 72833.6 87814.6 92155.0 94382.0 9865.4 10003.7 10007.1 10113.3 10124.9 44003.6 46645.8 47820.8 55117.4 55696.0 57161.7 59697.0 77778.0 78698.0 80736.2 13448.6 13463.5 13664.5 13697.9 14135.8 14510.9 14643.9 15246.4 15337.2 15349.5 15191.6 15267.2 15457.1 15764.6 15783.8 14247.9 14639.7 14705.6 14716.3 14792.9 14892.7 15196.0 15212.2 15216.5 15250.5 11037.8 11185.6 11339.4 11379.3 11539.0 11686.9 11884.6 11925.8 12076.3 12242.3 06:16:22 INFO - PROCESS | 7886 | -------- Summary: end -------- 06:16:22 INFO - PROCESS | 7886 | 06:16:23 INFO - TEST-INFO | 7886: exit 0 06:16:23 INFO - TEST-OK | dromaeo_css | took 1112745ms 06:16:23 INFO - TEST-START | kraken 06:16:23 INFO - operating with platform_type : linux_ 06:16:23 INFO - Initialising browser for kraken test... 06:16:23 INFO - TEST-INFO | started process 8076 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMaQOuS/profile http://localhost:41969/getInfo.html) 06:16:26 INFO - PROCESS | 8076 | __metrics Screen width/height:1600/1200 06:16:26 INFO - PROCESS | 8076 | colorDepth:24 06:16:26 INFO - PROCESS | 8076 | Browser inner width/height: 1024/697 06:16:26 INFO - PROCESS | 8076 | __metrics 06:16:27 INFO - PROCESS | 8076 | 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" 06:16:27 INFO - PROCESS | 8076 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:16:28 INFO - TEST-INFO | 8076: exit 0 06:16:28 INFO - Browser initialized. 06:16:28 INFO - Running cycle 1/1 for kraken test... 06:16:28 INFO - TEST-INFO | started process 8159 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMaQOuS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:40 INFO - PROCESS | 8159 | RSS: Main: 157016064 06:16:40 INFO - PROCESS | 8159 | 06:16:42 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-beat-detection) 06:16:43 INFO - PROCESS | 8159 | RSS: Main: 215330816 06:16:43 INFO - PROCESS | 8159 | 06:16:46 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-dft) 06:16:46 INFO - PROCESS | 8159 | RSS: Main: 203157504 06:16:46 INFO - PROCESS | 8159 | 06:16:49 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-fft) 06:16:50 INFO - PROCESS | 8159 | RSS: Main: 268763136 06:16:50 INFO - PROCESS | 8159 | 06:16:52 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-oscillator) 06:16:53 INFO - PROCESS | 8159 | RSS: Main: 180191232 06:16:53 INFO - PROCESS | 8159 | 06:16:55 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:16:55 INFO - PROCESS | 8159 | RSS: Main: 184717312 06:16:55 INFO - PROCESS | 8159 | 06:16:58 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-darkroom) 06:16:59 INFO - PROCESS | 8159 | RSS: Main: 219054080 06:16:59 INFO - PROCESS | 8159 | 06:17:02 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:03 INFO - PROCESS | 8159 | RSS: Main: 221069312 06:17:03 INFO - PROCESS | 8159 | 06:17:06 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41969/tests/kraken/driver.html?testName=json-parse-financial) 06:17:06 INFO - PROCESS | 8159 | RSS: Main: 221171712 06:17:06 INFO - PROCESS | 8159 | 06:17:08 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:08 INFO - PROCESS | 8159 | RSS: Main: 210128896 06:17:08 INFO - PROCESS | 8159 | 06:17:10 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:11 INFO - PROCESS | 8159 | RSS: Main: 212086784 06:17:11 INFO - PROCESS | 8159 | 06:17:13 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:13 INFO - PROCESS | 8159 | RSS: Main: 209956864 06:17:13 INFO - PROCESS | 8159 | 06:17:16 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:17 INFO - PROCESS | 8159 | RSS: Main: 223518720 06:17:17 INFO - PROCESS | 8159 | 06:17:19 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:20 INFO - PROCESS | 8159 | RSS: Main: 224714752 06:17:20 INFO - PROCESS | 8159 | 06:17:21 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41969/tests/kraken/driver.html?testName=ai-astar) 06:17:22 INFO - PROCESS | 8159 | RSS: Main: 267960320 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | __start_tp_report 06:17:22 INFO - PROCESS | 8159 | _x_x_mozilla_page_load 06:17:22 INFO - PROCESS | 8159 | _x_x_mozilla_page_load_details 06:17:22 INFO - PROCESS | 8159 | |i|pagename|runs| 06:17:22 INFO - PROCESS | 8159 | |0;ai-astar;104;111;112;113;113;112;111;110;110;110 06:17:22 INFO - PROCESS | 8159 | |1;audio-beat-detection;145;118;119;173;117;126;146;117;156;120 06:17:22 INFO - PROCESS | 8159 | |2;audio-dft;161;162;160;160;160;160;160;161;162;161 06:17:22 INFO - PROCESS | 8159 | |3;audio-fft;77;74;76;116;75;76;74;76;107;75 06:17:22 INFO - PROCESS | 8159 | |4;audio-oscillator;85;83;112;84;84;85;113;85;85;121 06:17:22 INFO - PROCESS | 8159 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;118;117 06:17:22 INFO - PROCESS | 8159 | |6;imaging-darkroom;145;146;145;146;145;148;146;145;145;145 06:17:22 INFO - PROCESS | 8159 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 06:17:22 INFO - PROCESS | 8159 | |8;json-parse-financial;52;54;53;51;51;52;53;53;52;53 06:17:22 INFO - PROCESS | 8159 | |9;json-stringify-tinderbox;61;61;61;61;61;60;61;60;60;60 06:17:22 INFO - PROCESS | 8159 | |10;stanford-crypto-aes;76;76;75;75;74;76;80;75;75;78 06:17:22 INFO - PROCESS | 8159 | |11;stanford-crypto-ccm;121;130;119;125;129;128;125;121;167;121 06:17:22 INFO - PROCESS | 8159 | |12;stanford-crypto-pbkdf2;158;161;157;159;154;155;159;156;157;154 06:17:22 INFO - PROCESS | 8159 | |13;stanford-crypto-sha256-iterative;63;62;65;63;63;61;62;63;60;62 06:17:22 INFO - PROCESS | 8159 | __end_tp_report 06:17:22 INFO - PROCESS | 8159 | __start_cc_report 06:17:22 INFO - PROCESS | 8159 | _x_x_mozilla_cycle_collect,1616 06:17:22 INFO - PROCESS | 8159 | __end_cc_report 06:17:22 INFO - PROCESS | 8159 | __startTimestamp1468329442215__endTimestamp 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | ------- Summary: start ------- 06:17:22 INFO - PROCESS | 8159 | Number of tests: 14 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.50 stddev:2.59 (2.3%) stddev-sans-first:1.22 06:17:22 INFO - PROCESS | 8159 | Values: 104.0 111.0 112.0 113.0 113.0 112.0 111.0 110.0 110.0 110.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:135.50 stddev:19.97 (14.7%) stddev-sans-first:20.76 06:17:22 INFO - PROCESS | 8159 | Values: 145.0 118.0 119.0 173.0 117.0 126.0 146.0 117.0 156.0 120.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 06:17:22 INFO - PROCESS | 8159 | Values: 161.0 162.0 160.0 160.0 160.0 160.0 160.0 161.0 162.0 161.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.50 stddev:15.41 (20.4%) stddev-sans-first:16.21 06:17:22 INFO - PROCESS | 8159 | Values: 77.0 74.0 76.0 116.0 75.0 76.0 74.0 76.0 107.0 75.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:84.50 stddev:15.12 (17.9%) stddev-sans-first:15.71 06:17:22 INFO - PROCESS | 8159 | Values: 85.0 83.0 112.0 84.0 84.0 85.0 113.0 85.0 85.0 121.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 06:17:22 INFO - PROCESS | 8159 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 118.0 117.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.97 (0.7%) stddev-sans-first:1.00 06:17:22 INFO - PROCESS | 8159 | Values: 145.0 146.0 145.0 146.0 145.0 148.0 146.0 145.0 145.0 145.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:17:22 INFO - PROCESS | 8159 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 06:17:22 INFO - PROCESS | 8159 | Values: 52.0 54.0 53.0 51.0 51.0 52.0 53.0 53.0 52.0 53.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 06:17:22 INFO - PROCESS | 8159 | Values: 61.0 61.0 61.0 61.0 61.0 60.0 61.0 60.0 60.0 60.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.00 stddev:1.76 (2.3%) stddev-sans-first:1.87 06:17:22 INFO - PROCESS | 8159 | Values: 76.0 76.0 75.0 75.0 74.0 76.0 80.0 75.0 75.0 78.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#11] stanford-crypto-ccm Cycles:10 Average:128.60 Median:126.50 stddev:14.02 (11.1%) stddev-sans-first:14.60 06:17:22 INFO - PROCESS | 8159 | Values: 121.0 130.0 119.0 125.0 129.0 128.0 125.0 121.0 167.0 121.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.00 Median:157.50 stddev:2.31 (1.5%) stddev-sans-first:2.42 06:17:22 INFO - PROCESS | 8159 | Values: 158.0 161.0 157.0 159.0 154.0 155.0 159.0 156.0 157.0 154.0 06:17:22 INFO - PROCESS | 8159 | 06:17:22 INFO - PROCESS | 8159 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:1.35 (2.1%) stddev-sans-first:1.41 06:17:22 INFO - PROCESS | 8159 | Values: 63.0 62.0 65.0 63.0 63.0 61.0 62.0 63.0 60.0 62.0 06:17:22 INFO - PROCESS | 8159 | -------- Summary: end -------- 06:17:22 INFO - PROCESS | 8159 | 06:17:23 INFO - TEST-INFO | 8159: exit 0 06:17:23 INFO - TEST-OK | kraken | took 60059ms 06:17:23 INFO - SUITE-END | took 1172s 06:17:23 INFO - Completed test suite (00:19:32) 06:17:23 INFO - javascript benchmark 06:17:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.449839028975, 3061.280599059219, 3091.1962889648685, 3096.2723759916416, 3102.751779857612, 5182.170852663565, 5224.45143259958, 5319.78720851166, 5353.0899747042995, 5380.704061276633, 5318.973405132969, 5322.787088516465, 5325.147976323789, 5386.0, 5391.946080539194, 4780.1873681474235, 4784.784684689189, 4839.153148199051, 4849.199882019462, 5020.146575082226, 4889.853304400874, 4901.754912254391, 4911.45973942866, 4959.578435832958, 4976.975115124419, 4780.163471392492, 4838.830640927586, 4841.7579121044155, 4844.660873738822, 4848.97575512122, 4823.493533179036, 4901.779419926076, 4905.068037072981, 4915.631327650441, 4935.80256789728, 4697.459792123916, 4797.52024797521, 4891.706497610119, 4894.461609222983, 4928.137575924235, 5339.065663508871, 5370.4361042090795, 5390.299261096072, 5391.326084239431, 5401.162819762943, 5713.771449142071, 5717.742701578439, 5731.369549349568, 5741.511971482429, 5746.367899531048, 5502.972485137589, 5723.0, 5744.253247077895, 5794.478496935254, 5832.737526811304, 5391.191321301758, 5430.321209848769, 5455.427180146067, 5458.044842152568, 5459.3994660587305, 5967.1347654589845, 5967.343592204854, 5967.403259673998, 5968.104784282393, 5969.671668058299, 5407.486288802557, 5521.089020311684, 5529.364123125812, 5572.275604171513, 5700.429957004267, 5709.571782116358, 5732.627379220337, 5737.426257374313, 5739.225204597409, 5756.798512052058, 5686.289213848269, 5691.0, 5694.715264236855, 5698.230738850285, 5729.0260655688635, 5502.8349149525575, 5632.436756324417, 5645.887082258414, 5675.943240567624, 5721.256236689203, 4404.889877753018, 4407.6473882089995, 4408.889777755518, 4410.272305069691, 4443.844465443694, 3913.9217215655526, 3942.073612701013, 3942.231264903374, 3958.44581758554, 3963.504561929759, 5402.810901618424, 5413.0, 5448.210009548593, 5488.25908502355, 5528.695921724263, 5700.515456186187, 5732.570057245724, 5743.4256574342235, 5767.279090113736, 5790.42095790426, 5316.202569614511, 5404.648697834628, 5453.0, 5470.8085217017215, 5471.480209380182, 4865.6107511399005, 4924.975375123101, 4934.259861020875, 4946.307516947631, 4952.0, 6017.578769486182, 6019.067044608093, 6019.367966363557, 6020.789272375446, 6032.426919442733, 5586.301712285965, 5731.770729170787, 5794.5364370851075, 5796.333421656394, 5810.128480727924, 5988.730507127277, 6051.062085376773, 6052.969735151296, 6058.909116363347, 6120.234970628671, 5676.545876329967, 5702.059160238514, 5707.743151558107, 5727.427257274406, 5729.369769325454, 5565.721713914207, 5693.914591281051, 5743.971280143573, 5812.128180772918, 5817.505512031525, 5674.375818659862, 5677.148427735872, 5680.71596420169, 5681.034224181982, 5720.885582288248, 5595.8880822384135, 5645.71771411436, 5646.85882852932, 5647.463490968352, 5657.660540367591, 5445.727713614383, 5556.0, 5629.099344104923, 5639.8590035249445, 5645.237892884408, 5645.266115404971, 5650.717464126859, 5653.575981801464, 5703.230063941481, 5708.8572785679025, 5428.348598168245, 5459.399466058651, 5477.233187353694, 5477.260569823182, 5493.395726470005], "value": 5295.52834703238, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.2360155399534, 621.9626822390657, 627.9748810047597, 637.0539748473519, 642.5277421095494, 32550.511742323855, 43225.0, 43495.99999999992, 43545.0, 47754.0, 46090.76954615222, 46290.30564541535, 46474.535254647446, 46489.99999999991, 46517.767411162895, 8808.295336373074, 8922.955385223097, 8951.910480895205, 8958.0, 8962.283017358628, 8837.248833849098, 8934.10658934108, 8944.15030572098, 8944.552772361389, 8949.373543851932, 8954.731358059269, 8991.370604057718, 8992.68525601604, 8995.820083598324, 9003.459792412443, 8915.68795092172, 8942.731718048437, 8954.149355811187, 8959.5968181432, 8960.372773905829, 8832.205101540892, 8875.600597973042, 8889.777755556099, 8893.0, 8896.510691911943, 43885.56114438878, 43968.0, 44013.559864401264, 44334.55665443336, 44343.77828110839, 3486.6513348665185, 3577.0342007657787, 3605.6394360563995, 3704.2776658551593, 3795.772253664789, 31292.687073129433, 33973.32053358919, 34114.0, 34127.48808767871, 34253.0, 4497.302918047708, 4527.524609915944, 4533.297338912441, 4550.158220729142, 4595.126925884105, 369648.42812575196, 369869.0, 370056.0, 371912.9999999973, 372887.0, 61301.38698613046, 61382.38617613856, 61698.457538561895, 61784.0, 61824.38175618276, 57750.7112464435, 63525.36474635287, 64105.35894641087, 65033.0, 65198.0, 17322.307107715802, 17476.650466990843, 17490.825091749175, 17497.56256093608, 17498.212580433912, 13312.06815522904, 13404.128731632412, 13404.262765547794, 13406.262655554037, 13418.798718019238, 17249.0, 18784.154713037948, 18787.15457804402, 19078.332258371167, 19767.802321976884, 7071.717131314793, 7073.6913670971035, 7088.184858741208, 7405.666744996488, 7429.777106686808, 982.8378317577663, 1088.5972190289485, 1102.250469680625, 1157.3808012713157, 1161.5702190189684, 33942.15144621404, 34069.48895766566, 34235.0, 34297.31405371879, 34697.479537806954, 1117.7987962166726, 1134.4157758754247, 1134.6822889590928, 1203.4404002139047, 1224.2593231095236, 17152.056636885092, 17536.736948945778, 17665.38171164003, 17744.645107097786, 17826.5543361417, 581045.0, 593024.0, 594675.0, 594691.0, 597673.0, 60540.881069162366, 60842.391576084556, 60919.0, 61043.0, 61086.69456652688, 1249.7063190150307, 1291.0575280045432, 1362.8228330316995, 1371.6502291915697, 1391.8538553451945, 30484.542731859485, 35325.07248103566, 35624.64375356213, 35680.0, 35805.56777728862, 51452.48547514477, 51724.0, 51974.99999999849, 52047.73976130095, 52050.0, 338731.0, 340258.0, 340413.0, 341109.0, 341293.0, 28329.858350708113, 29246.0, 29343.559846601893, 29389.0, 29406.0, 49870.75064624799, 51230.0, 51666.48333516767, 52325.0, 52327.0, 42339.0, 42937.35593966155, 43426.782866085465, 43708.0, 44871.77564112159, 42254.1549169021, 42549.36175957425, 42598.361024585276, 42790.786046069574, 43115.56884431116, 42578.5742142587, 42757.1448571033, 43165.0, 43468.34797477977, 43598.782006089765, 37323.626763733104, 38212.0, 38569.6143038566, 39099.80450097731, 39170.80414597909, 29097.709022910345, 29321.413571728874, 29335.0, 29546.55680164842, 29728.554071689374], "value": 20699.847006619384, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.2418726266575, 397.23333965446693, 398.20955403524, 402.18156052433295, 418.09482470451474, 14388.640283992892, 19146.32987845424, 19214.231430742755, 20153.496162595944, 20359.69460458094, 19134.71297930532, 19211.32760353388, 19951.40145795625, 20030.399088027345, 20052.298169564066, 5772.595918285721, 5812.82561523155, 5929.703514824263, 5930.347661757203, 5991.2510936132985, 5695.14572814077, 5832.183494310779, 5862.091375836752, 5875.559333050017, 5907.995640741085, 5747.166660834177, 5789.884202315951, 5800.0, 5901.763929442838, 5905.43898329658, 5845.766169353221, 5907.88184236315, 5910.645361278337, 5913.704314784265, 5944.0, 5129.769160387791, 5196.662216955886, 5221.6083793715625, 5229.607779416559, 5241.371035475768, 22175.334739957667, 22279.0, 22933.312000640006, 23193.76806231933, 23214.0, 2256.4810093678384, 2381.2380038387723, 2446.0704932125855, 2569.2252367443116, 3092.8762849485993, 10468.633597824115, 10544.314619549705, 10706.518206680717, 10767.730806729816, 10820.458977051116, 1993.8604297699167, 2006.1173083843062, 2031.5632139090167, 2052.5997430501056, 2058.8661736987196, 70507.64746176236, 83595.16404835995, 86880.13119868784, 87196.12803872007, 87712.56143719304, 10824.13406927444, 10955.726106847393, 10990.725231869268, 11245.003847967928, 11292.209545331913, 15595.532134035997, 15603.765943510856, 15623.062616243062, 15634.296456659478, 15703.371865125495, 7985.441019128605, 8079.046212699334, 8136.389770767216, 8379.455335403301, 8435.620397082146, 7722.92277077222, 7781.416393770377, 7873.881891771628, 7907.446478746548, 7996.520208787491, 4886.92669609949, 4909.189983652657, 4909.410870695539, 4910.74775932135, 4967.801287948441, 1971.5465442948203, 2002.469345623411, 2026.696484729881, 2030.502526880894, 2055.0649454498166, 928.5774972387547, 1020.6886899495726, 1040.6461802987021, 1062.1821197677746, 1111.3165403276926, 19755.0, 20157.899210504147, 20238.59522809565, 20243.595128097357, 20511.282105126247, 981.9803603927882, 1008.3949630221955, 1054.6625079974335, 1074.2694967422071, 1083.34999000599, 10860.294080884718, 11429.371384573762, 11548.480318385693, 11571.0, 11586.304821710555, 409552.0, 446066.0, 470487.0, 486485.0, 490244.0, 72147.63926180336, 97421.51289243509, 97730.0, 97758.0, 98045.0, 1005.8491226315965, 1025.4001409175717, 1035.0684384054412, 1042.2339580408393, 1047.382044593678, 17662.116894154984, 17680.82319176792, 18183.45449636513, 18213.817861821743, 18535.165917533475, 40201.0, 42458.57541424546, 42633.147337053706, 42679.3598096035, 42960.0, 56479.435205647416, 56889.0, 57892.55268618318, 63002.68498657477, 66537.66731166313, 8665.053881680258, 8679.86980195285, 8697.869531957153, 9003.909960900308, 9123.27013838878, 10466.895331046591, 13590.660233494242, 14223.715525689635, 14319.212443315717, 14728.410863565337, 12001.1599188056, 12143.817842732542, 12285.447154878231, 12813.93593032029, 12926.741465170831, 12050.879491205327, 12287.324197169242, 12417.813732794197, 12531.74936501283, 12673.873261267268, 11977.520899164285, 12529.37353132358, 12530.874691253335, 12549.309787961749, 12621.242725436503, 8763.3427492937, 8836.734897953073, 8925.553722313989, 8998.055204203727, 9008.954955225181, 9083.500407477652, 11864.940675296568, 12496.500139994298, 12788.168769029984, 12908.160969537326], "value": 9438.561990075968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.459416233507, 1494.5815171751906, 1521.3458212968424, 1655.2137734576079, 1813.8548916086716, 8475.70335038273, 8827.073157318486, 8831.161039701234, 8996.95501522493, 9075.092490750922, 8779.165979231962, 8997.59510822013, 9000.729978100651, 9008.369414141012, 9021.864672029924, 1945.5816999345138, 2194.429448343434, 2225.6772767948637, 2271.5116250006245, 2271.943201419961, 2312.4103353644796, 2323.4191452136965, 2323.93028209154, 2324.976750232501, 2326.4765427778784, 2250.5723912456583, 2256.7968882800546, 2285.9771402286015, 2285.988570057147, 2286.942826429336, 1863.9347622833272, 1921.6541022615922, 1930.7393501877207, 2167.2522979572022, 2212.0819859758262, 2201.0975500045065, 2235.407616981501, 2235.407616981501, 2238.2837492002564, 2239.261043855524, 8912.420692654956, 8955.865662015074, 8965.282777377794, 8968.865467018, 9014.278857691435, 5029.949700502984, 5129.230615407681, 5138.049460849755, 5139.15203991341, 5144.356955380577, 3147.653758086609, 3223.226425657825, 3277.246233366315, 3277.9344413111844, 3278.672132786702, 976.6239997600903, 1011.8532812742185, 1028.511457057899, 1031.0, 1043.3879655931603, 12332.445039973223, 12352.320622365767, 12365.07261955357, 12370.381480926007, 12379.75240495185, 4371.912561748747, 4490.595846373842, 4586.1057093866375, 4628.768561571908, 4630.073985202973, 10294.073533381881, 10352.844707329396, 10380.169586433067, 10407.271490995709, 10408.47957602117, 5281.287026251407, 5293.285406470077, 5295.470452954673, 5299.947000529945, 5300.072487314705, 4100.712950093524, 4693.06138772247, 4816.253480710496, 4819.879503012453, 4890.997345544155, 3963.6630886374983, 3991.341428664238, 4039.636432721069, 4078.0, 4108.609682080198, 1115.1079136690614, 1202.4829323391025, 1244.6577191272472, 1255.0649765924263, 1258.7482503499336, 886.3751055505853, 898.9235914947172, 914.1041779056562, 921.6221349246777, 921.6589861751195, 6036.105386009818, 6087.178230938855, 6224.470919971763, 6231.158793562901, 6255.624662520263, 963.4315753705348, 964.1852634523816, 985.7387792235063, 998.1715176403568, 1009.8232809258351, 3934.2918274710255, 4098.713090083726, 4109.527404348479, 4142.482189726285, 4164.937525937114, 11230.0, 11513.88486115145, 11559.248648837798, 11575.42122893852, 11576.073914086854, 11390.601328953613, 11456.427178641035, 11474.311541307548, 11495.942520287512, 11496.885031149748, 7985.161558036322, 8330.375221858385, 8330.750077497685, 8430.831383372299, 8569.057403685589, 5819.738111785065, 5856.121581762599, 5882.852928676817, 5960.225170727777, 6200.286966998853, 10308.690739277834, 10309.22680798928, 10408.230600798033, 10429.9478502607, 10429.947850261004, 10041.49792510357, 10206.438645874548, 10543.26197166191, 10592.841107383549, 10592.947035264775, 8024.638891250024, 8085.272325490734, 8183.0, 8204.712835050745, 8214.4660597061, 8589.871151932852, 8594.140585941355, 8651.783705407415, 8715.259202967818, 8795.868061978947, 6290.0, 7826.0, 7920.485168464032, 8085.716999904975, 8287.461315014505, 7748.108967468813, 7820.843583128191, 7876.8818467721885, 8047.798805029921, 8065.758027259191, 7316.634168291671, 7333.816654583678, 7383.96624472585, 7391.519551229153, 7416.0729908761405, 5243.947560524346, 5260.500252476008, 5278.392984806796, 5300.337457817773, 5329.040772660803, 5051.62112841531, 5216.40011398694, 5216.556592689664, 5315.0, 5409.8377048687025], "value": 4635.932021048003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.06184097039073, 336.4734190991099, 339.49245877413256, 341.2696828786397, 341.38720995812514, 5050.292958985745, 5214.687118772871, 5230.686158830473, 5264.973675131624, 5284.497972692598, 5232.3459567554055, 5250.789968401269, 5261.842144735654, 5269.130593452076, 5269.920951185725, 4132.235536425764, 4135.875923722293, 4150.97924510377, 4158.6465150462245, 4176.540580536139, 3971.8212680429306, 3986.70099742519, 4153.314703073989, 4179.895502612444, 4188.81150348235, 4090.0, 4113.650339721113, 4114.547399786021, 4115.012397024722, 4119.8970025749295, 4102.712810103294, 4104.507459104897, 4105.178964207155, 4109.794510274504, 4138.668906487474, 3458.602260739997, 3645.453182022691, 3649.178934739689, 3664.5236119304586, 3672.8714494992546, 5119.667221630582, 5133.845984620468, 5135.974320128413, 5141.460146684619, 5150.253213284091, 4679.719216847, 4693.272542755844, 4699.801550604574, 4700.7179569225955, 4708.375610414436, 5318.165232994611, 5373.596980226499, 5459.481349271852, 5475.589330800206, 5475.863103422406, 3927.9410808837893, 3938.090026337224, 3956.149427873021, 3957.1294315250598, 3971.62269584389, 5666.263385759866, 5695.77216911323, 5702.65784052954, 5702.914456283159, 5716.742746576372, 3966.087799806061, 4044.1102957349335, 4055.1889622075114, 4058.817353219112, 4070.1249231415386, 4028.7179897407486, 4103.363978583325, 4104.486939132608, 4106.178764247163, 4109.856155034559, 4621.92641893144, 4657.510961349009, 4658.091672123904, 4660.555227879368, 4674.415698037746, 5461.371942226615, 5503.642263252876, 5506.697131657717, 5506.724663766796, 5512.3385193777, 1278.673938145767, 1313.3039489070807, 1400.649837540615, 1407.8451370349253, 1410.8024876517295, 957.6360982826482, 958.9856152157723, 972.1202311907734, 980.8822941247096, 987.244757760314, 5351.438098999627, 5354.759035843318, 5356.437574054668, 5366.892662146735, 5376.892462150813, 5565.220869078333, 5572.4148964358155, 5588.2455868458055, 5595.860103497445, 5598.412166722517, 5235.240890070919, 5375.274337964403, 5375.704336261465, 5394.217838413408, 5493.2034854945805, 2997.970020299813, 3000.294930691286, 3011.5482677598097, 3028.6825231024577, 3035.7723170762283, 5864.560157988168, 5878.676672782953, 5879.853003674943, 5943.31651860033, 5948.196993405922, 5607.327120745455, 5803.912941305884, 5809.854753631109, 5812.534997200214, 5818.76724930998, 5820.505257053113, 5851.38560451155, 5852.590318677651, 5883.882322353444, 5884.32330282023, 5375.408705042364, 5679.160419790105, 5682.886342273214, 5684.971575142098, 5691.288588926384, 5600.383957764724, 5650.3219613646625, 5671.829845104653, 5674.4325567444575, 5677.318721753416, 5898.203742494708, 5939.613925094855, 5947.256592925884, 5948.494377977747, 5975.342712301556, 3928.4286285486032, 4005.399190121505, 4047.8785636430953, 4064.7357921734992, 4074.8777536673947, 5661.801836935688, 5682.346530149086, 5699.287589051369, 5704.919343554434, 5718.199452076796, 5479.479449452296, 5550.306211723535, 5581.609287349846, 5616.0, 5619.943800561942, 5543.085390910454, 5558.777648894161, 5561.304836895388, 5565.721713914207, 5583.720813959205, 5491.917621235605, 5537.972310138423, 5548.611597188158, 5567.888642227052, 5578.358488773681, 5395.730213489388, 5491.75287112073, 5544.085225937675, 5555.3611334697025, 5600.187972743848, 5384.515393614593, 5414.539764119936, 5464.18037294409, 5472.616916815944, 5481.314835645544], "value": 4226.1727960676835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.6252442418885, 763.9427042971777, 765.5253742679539, 770.7186876789966, 859.6948083430382, 15453.381864725412, 16774.83225167748, 16778.496645100637, 16823.41118060868, 16845.241964111614, 15067.397304107822, 15228.0, 15258.160801155933, 15259.389624415011, 15331.386744530206, 659.0, 793.2107552984788, 820.9261166495014, 821.6590115102225, 822.9341652667803, 806.8628333183373, 813.9918600814004, 818.7093581778472, 822.48183644304, 823.1233736071065, 704.6688056613409, 873.5195642396689, 875.9824803503926, 876.6712482818942, 880.1506546182932, 717.3938022371088, 723.0130871360582, 814.5031530766228, 834.5284914023557, 840.260570697782, 613.3927411862225, 613.7115555688864, 613.7575657615262, 613.8741557980647, 613.8772245550863, 15061.0, 15084.773728394082, 15099.924500377538, 15118.470853520073, 15246.237688115662, 14593.197374144418, 14858.554243372608, 14927.179005154821, 14970.775438368433, 14971.850281497154, 12133.150679452441, 12327.44526496301, 12467.75064498714, 13165.86834131656, 13585.86414135856, 4718.598919091882, 4809.350737650408, 4816.975915120437, 4943.9258411123865, 4971.105201063807, 78263.21736782673, 80087.5995620024, 80090.0, 80288.0, 80536.59731701363, 11697.356645384585, 11773.411329433495, 11934.471941442907, 11994.160408771477, 12155.209911355734, 21607.459813504473, 21626.567468650854, 21640.160586350336, 21656.458588535414, 21672.13311467555, 8958.10418958112, 9038.457692538337, 9184.954075229582, 9240.306976976754, 9326.393784403992, 4899.240617704261, 4949.232868905326, 5005.34930459038, 5088.643794934319, 5131.1277082895995, 3429.9314013719586, 3554.928901421957, 3558.893233203008, 3569.268299998494, 3571.678548930609, 637.5250438423404, 642.1235014205575, 644.861354808709, 653.8463461086641, 656.937590928861, 725.9310365515267, 750.7147284032033, 756.5498528375608, 777.4946284914738, 785.3952456608494, 10032.849507257397, 10033.247506437046, 10379.429131397847, 10530.947345263225, 10545.156387488982, 763.1109757132989, 770.5569297653872, 780.4887798491997, 780.6096951524238, 791.4815795653856, 5088.592912566985, 5167.199084141964, 5224.791008359623, 5230.34620672416, 5233.476652334736, 114807.42596286965, 117453.41273293579, 119857.0, 121068.0, 124381.0, 91148.0, 92148.0, 93582.53208733913, 95602.0, 97958.0, 58282.7085864568, 72833.6358318205, 87814.56092719495, 92155.0, 94382.0, 9865.408075515492, 10003.749906252402, 10007.149392301735, 10113.342632728849, 10124.898751012395, 44003.559964401225, 46645.76677116593, 47820.760896195294, 55117.44882551123, 55696.0, 57161.71419142878, 59697.0, 77778.0, 78698.0, 80736.19263807287, 13448.596542103753, 13463.528776492776, 13664.453421863014, 13697.931510342783, 14135.787963180766, 14510.927445362706, 14643.85356146425, 15246.390144394543, 15337.15645639456, 15349.539513814603, 15191.620209494851, 15267.160306183267, 15457.149856757986, 15764.644149518344, 15783.763243551126, 14247.857521425069, 14639.707205856037, 14705.632359190678, 14716.264186790404, 14792.926035369754, 14892.702145957237, 15196.0, 15212.163331016458, 15216.467423640119, 15250.466233681767, 11037.779244414905, 11185.608503702331, 11339.376334301693, 11379.317240965569, 11539.0, 11686.88313116892, 11884.643460696192, 11925.76148477043, 12076.275423474619, 12242.326672033123], "value": 7827.752556955454, "unit": "score"}], "name": "dromaeo_css", "value": 7357.9766686812345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 112.0, 113.0, 113.0, 112.0, 111.0, 110.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 119.0, 173.0, 117.0, 126.0, 146.0, 117.0, 156.0, 120.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 160.0, 160.0, 160.0, 160.0, 161.0, 162.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 76.0, 116.0, 75.0, 76.0, 74.0, 76.0, 107.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 112.0, 84.0, 84.0, 85.0, 113.0, 85.0, 85.0, 121.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 148.0, 146.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 51.0, 51.0, 52.0, 53.0, 53.0, 52.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 75.0, 74.0, 76.0, 80.0, 75.0, 75.0, 78.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 130.0, 119.0, 125.0, 129.0, 128.0, 125.0, 121.0, 167.0, 121.0], "value": 128.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 157.0, 159.0, 154.0, 155.0, 159.0, 156.0, 157.0, 154.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 65.0, 63.0, 63.0, 61.0, 62.0, 63.0, 60.0, 62.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1476.0, "alertThreshold": 2.0}]} 06:17:23 INFO - Return code: 0 06:17:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:17:23 INFO - # TBPL SUCCESS # 06:17:23 INFO - Running post-action listener: _resource_record_post_action 06:17:23 INFO - ##### 06:17:23 INFO - ##### Finished run-tests step (success) 06:17:23 INFO - ##### 06:17:23 INFO - Running post-run listener: _resource_record_post_run 06:17:23 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 556744704; Read time: 0; Write time: 543568 06:17:23 INFO - TinderboxPrint: CPU usage
12.9% 06:17:23 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:17:23 INFO - TinderboxPrint: I/O write bytes / time
556,744,704 / 543,568 06:17:23 INFO - TinderboxPrint: CPU idle
8,268.7 (87.1%) 06:17:23 INFO - TinderboxPrint: CPU user
1,186.6 (12.5%) 06:17:23 INFO - TinderboxPrint: Swap in / out
0 / 0 06:17:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 257036288; Read time: 0; Write time: 327760 06:17:23 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 299323392; Read time: 0; Write time: 215768 06:17:24 INFO - Running post-run listener: _upload_blobber_files 06:17:24 INFO - Blob upload gear active. 06:17:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:24 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 06:17:24 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:17:24 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:17:24 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:17:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:25 INFO - (blobuploader) - INFO - Done attempting. 06:17:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:17:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:17:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:26 INFO - (blobuploader) - INFO - Done attempting. 06:17:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:17:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:17:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:31 INFO - (blobuploader) - INFO - Done attempting. 06:17:31 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:31 INFO - Return code: 0 06:17:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b371b92883db8e8c301941ff9edec2061746696142dc3f676b2bbbc96d95b7448c6a7441c8beb6b1288df93a759fd21365658ff58d91a085bf280623d6a2a683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cd1e54950dd31a5d7203c399b14f4029b9fef5430804983ace363b891a55b3f6116c1b5dedd12fdab4924b671b1f1f8d5fdeea641320dbf822cce72537e8983b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7f4ffc939971ba392c4e33bb37548bb0d9d89555c26bd93a18ec4584bef447034557d7703407a151556867a7b9c7634a53675e9a67dbc8765129e4e3692a944"} 06:17:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:31 INFO - Contents: 06:17:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b371b92883db8e8c301941ff9edec2061746696142dc3f676b2bbbc96d95b7448c6a7441c8beb6b1288df93a759fd21365658ff58d91a085bf280623d6a2a683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cd1e54950dd31a5d7203c399b14f4029b9fef5430804983ace363b891a55b3f6116c1b5dedd12fdab4924b671b1f1f8d5fdeea641320dbf822cce72537e8983b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7f4ffc939971ba392c4e33bb37548bb0d9d89555c26bd93a18ec4584bef447034557d7703407a151556867a7b9c7634a53675e9a67dbc8765129e4e3692a944"} 06:17:31 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:31 INFO - Copying logs to upload dir... 06:17:31 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.773676 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-12 06:17:30.845015) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 06:17:30.848815) ========= 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=62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b371b92883db8e8c301941ff9edec2061746696142dc3f676b2bbbc96d95b7448c6a7441c8beb6b1288df93a759fd21365658ff58d91a085bf280623d6a2a683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cd1e54950dd31a5d7203c399b14f4029b9fef5430804983ace363b891a55b3f6116c1b5dedd12fdab4924b671b1f1f8d5fdeea641320dbf822cce72537e8983b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7f4ffc939971ba392c4e33bb37548bb0d9d89555c26bd93a18ec4584bef447034557d7703407a151556867a7b9c7634a53675e9a67dbc8765129e4e3692a944"} build_url:https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011349 build_url: 'https://queue.taskcluster.net/v1/task/SadL0tOZTCSx272bAVazXA/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/Mozilla-Inbound/sha512/b371b92883db8e8c301941ff9edec2061746696142dc3f676b2bbbc96d95b7448c6a7441c8beb6b1288df93a759fd21365658ff58d91a085bf280623d6a2a683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cd1e54950dd31a5d7203c399b14f4029b9fef5430804983ace363b891a55b3f6116c1b5dedd12fdab4924b671b1f1f8d5fdeea641320dbf822cce72537e8983b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7f4ffc939971ba392c4e33bb37548bb0d9d89555c26bd93a18ec4584bef447034557d7703407a151556867a7b9c7634a53675e9a67dbc8765129e4e3692a944"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 06:17:30.875084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:17:30.875417) ========= 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=62c568580acbfdaac3eec7be000001dc-1468328217.888085-1552499097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:17:30.960183) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 06:17:30.960501) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 06:17:30.960794) ========= ========= Total master_lag: 0.18 =========