builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1468880713.6 results: success (0) buildid: 20160718124149 builduid: 77455f5ef6a9475dab8ef32eea89f417 revision: 2a69345f4f4eeb804e6a96c70f086f10e9849724 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.596763) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.597149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.597689) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.628574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.628827) ========= 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=33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 15:25:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 29.4M=0s 2016-07-18 15:25:15 (29.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130654 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.773233) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.773502) ========= 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=33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019856 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.856645) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:13.859957) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2a69345f4f4eeb804e6a96c70f086f10e9849724 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 2a69345f4f4eeb804e6a96c70f086f10e9849724 --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=33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 15:25:15,656 truncating revision to first 12 chars 2016-07-18 15:25:15,656 Setting DEBUG logging. 2016-07-18 15:25:15,656 attempt 1/10 2016-07-18 15:25:15,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2a69345f4f4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 15:25:15,987 unpacking tar archive at: autoland-2a69345f4f4e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662460 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:14.538575) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:14.538872) ========= script_repo_revision: 2a69345f4f4eeb804e6a96c70f086f10e9849724 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:14.539289) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:14.539608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 15:25:14.553289) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-18 15:25:14.553599) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:25:16 INFO - MultiFileLogger online at 20160718 15:25:16 in /builds/slave/test 15:25:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:25:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:25:16 INFO - {'append_to_log': False, 15:25:16 INFO - 'base_work_dir': '/builds/slave/test', 15:25:16 INFO - 'blob_upload_branch': 'Autoland', 15:25:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:25:16 INFO - 'branch': 'Autoland', 15:25:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:25:16 INFO - 'config_files': ('talos/linux_config.py',), 15:25:16 INFO - 'default_actions': ('clobber', 15:25:16 INFO - 'read-buildbot-config', 15:25:16 INFO - 'download-and-extract', 15:25:16 INFO - 'populate-webroot', 15:25:16 INFO - 'create-virtualenv', 15:25:16 INFO - 'install', 15:25:16 INFO - 'run-tests'), 15:25:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:25:16 INFO - 'download_minidump_stackwalk': True, 15:25:16 INFO - 'download_symbols': 'ondemand', 15:25:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:25:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:25:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:25:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:25:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:25:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:25:16 INFO - 'installer_path': 'installer.exe', 15:25:16 INFO - 'log_level': 'info', 15:25:16 INFO - 'log_name': 'talos', 15:25:16 INFO - 'log_to_console': True, 15:25:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:25:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:25:16 INFO - 'opt_config_files': (), 15:25:16 INFO - 'pip_index': False, 15:25:16 INFO - 'sps_profile': False, 15:25:16 INFO - 'sps_profile_interval': 0, 15:25:16 INFO - 'suite': 'dromaeojs', 15:25:16 INFO - 'system_bits': '32', 15:25:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:25:16 INFO - 'title': 'talos-linux64-ix-063', 15:25:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:25:16 INFO - 'use_talos_json': True, 15:25:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:25:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:25:16 INFO - 'work_dir': 'build'} 15:25:16 INFO - ##### 15:25:16 INFO - ##### Running clobber step. 15:25:16 INFO - ##### 15:25:16 INFO - Running pre-action listener: _resource_record_pre_action 15:25:16 INFO - Running main action method: clobber 15:25:16 INFO - rmtree: /builds/slave/test/build 15:25:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:25:17 INFO - Running post-action listener: _resource_record_post_action 15:25:17 INFO - ##### 15:25:17 INFO - ##### Finished clobber step (success) 15:25:17 INFO - ##### 15:25:17 INFO - ##### 15:25:17 INFO - ##### Running read-buildbot-config step. 15:25:17 INFO - ##### 15:25:17 INFO - Running pre-action listener: _resource_record_pre_action 15:25:17 INFO - Running main action method: read_buildbot_config 15:25:17 INFO - Using buildbot properties: 15:25:17 INFO - { 15:25:17 INFO - "project": "", 15:25:17 INFO - "product": "firefox", 15:25:17 INFO - "script_repo_revision": "production", 15:25:17 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:25:17 INFO - "repository": "", 15:25:17 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 15:25:17 INFO - "buildid": "20160718124149", 15:25:17 INFO - "pgo_build": "True", 15:25:17 INFO - "basedir": "/builds/slave/test", 15:25:17 INFO - "buildnumber": 49, 15:25:17 INFO - "slavename": "talos-linux64-ix-063", 15:25:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:25:17 INFO - "platform": "ubuntu64_hw", 15:25:17 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 15:25:17 INFO - "branch": "autoland", 15:25:17 INFO - "repo_path": "integration/autoland", 15:25:17 INFO - "revision": "2a69345f4f4eeb804e6a96c70f086f10e9849724", 15:25:17 INFO - "stage_platform": "linux64-pgo", 15:25:17 INFO - "builduid": "77455f5ef6a9475dab8ef32eea89f417", 15:25:17 INFO - "slavebuilddir": "test" 15:25:17 INFO - } 15:25:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:25:17 INFO - Running post-action listener: _resource_record_post_action 15:25:17 INFO - ##### 15:25:17 INFO - ##### Finished read-buildbot-config step (success) 15:25:17 INFO - ##### 15:25:17 INFO - ##### 15:25:17 INFO - ##### Running download-and-extract step. 15:25:17 INFO - ##### 15:25:17 INFO - Running pre-action listener: _resource_record_pre_action 15:25:17 INFO - Running main action method: download_and_extract 15:25:17 INFO - mkdir: /builds/slave/test/build/tests 15:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:17 INFO - https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:25:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:25:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:25:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:24 INFO - Downloaded 1361 bytes. 15:25:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:25:24 INFO - Using the following test package requirements: 15:25:24 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:25:24 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:25:24 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:25:24 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'jsshell-linux-x86_64.zip'], 15:25:24 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:25:24 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:25:24 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:25:24 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:25:24 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:25:24 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:25:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:25:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:24 INFO - https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:25:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:25:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:25:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:28 INFO - Downloaded 24335295 bytes. 15:25:28 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'] 15:25:28 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 15:25:30 INFO - Return code: 0 15:25:30 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 15:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:30 INFO - https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:25:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:25:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:25:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:32 INFO - Downloaded 24335295 bytes. 15:25:32 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'] 15:25:32 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 15:25:35 INFO - Return code: 0 15:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:35 INFO - https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:25:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:25:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:25:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:36 INFO - Downloaded 14403317 bytes. 15:25:36 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'] 15:25:36 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 15:25:37 INFO - Return code: 0 15:25:37 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')]} 15:25:37 INFO - https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:25:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:25:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:25:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHtqAl6kQvaD74dyISGAhg/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 15:25:39 INFO - Downloaded 60858976 bytes. 15:25:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:25:39 INFO - mkdir: /builds/slave/test/properties 15:25:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:25:39 INFO - Writing to file /builds/slave/test/properties/build_url 15:25:39 INFO - Contents: 15:25:39 INFO - build_url:https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:25:40 INFO - Running post-action listener: _resource_record_post_action 15:25:40 INFO - Running post-action listener: set_extra_try_arguments 15:25:40 INFO - ##### 15:25:40 INFO - ##### Finished download-and-extract step (success) 15:25:40 INFO - ##### 15:25:40 INFO - ##### 15:25:40 INFO - ##### Running populate-webroot step. 15:25:40 INFO - ##### 15:25:40 INFO - Running pre-action listener: _resource_record_pre_action 15:25:40 INFO - Running main action method: populate_webroot 15:25:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:25:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:25:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:25:40 INFO - '--tppagecycles', 15:25:40 INFO - '7'], 15:25:40 INFO - 'tests': ['tsvgm']}}, 15:25:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:25:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:25:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:25:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:25:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['damp', 'tps']}, 15:25:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'tests': ['damp', 'tps']}, 15:25:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['dromaeo_dom']}, 15:25:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:25:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['basic_compositor_video']}, 15:25:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:25:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['a11yr', 15:25:40 INFO - 'ts_paint', 15:25:40 INFO - 'tpaint', 15:25:40 INFO - 'sessionrestore', 15:25:40 INFO - 'sessionrestore_no_auto_restore', 15:25:40 INFO - 'tabpaint']}, 15:25:40 INFO - 'other-e10s': {'tests': ['a11yr', 15:25:40 INFO - 'ts_paint', 15:25:40 INFO - 'tpaint', 15:25:40 INFO - 'sessionrestore', 15:25:40 INFO - 'sessionrestore_no_auto_restore', 15:25:40 INFO - 'tabpaint']}, 15:25:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:25:40 INFO - 'ts_paint', 15:25:40 INFO - 'tpaint', 15:25:40 INFO - 'sessionrestore', 15:25:40 INFO - 'sessionrestore_no_auto_restore', 15:25:40 INFO - 'tabpaint']}, 15:25:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:25:40 INFO - 'ts_paint', 15:25:40 INFO - 'tpaint', 15:25:40 INFO - 'sessionrestore', 15:25:40 INFO - 'sessionrestore_no_auto_restore', 15:25:40 INFO - 'tabpaint']}, 15:25:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['a11yr', 15:25:40 INFO - 'ts_paint', 15:25:40 INFO - 'tpaint', 15:25:40 INFO - 'sessionrestore', 15:25:40 INFO - 'sessionrestore_no_auto_restore', 15:25:40 INFO - 'tabpaint']}, 15:25:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['a11yr', 15:25:40 INFO - 'ts_paint', 15:25:40 INFO - 'tpaint', 15:25:40 INFO - 'sessionrestore', 15:25:40 INFO - 'sessionrestore_no_auto_restore', 15:25:40 INFO - 'tabpaint']}, 15:25:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['tsvgx', 15:25:40 INFO - 'tsvgr_opacity', 15:25:40 INFO - 'tart', 15:25:40 INFO - 'tscrollx', 15:25:40 INFO - 'cart']}, 15:25:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:25:40 INFO - 'tsvgr_opacity', 15:25:40 INFO - 'tart', 15:25:40 INFO - 'tscrollx', 15:25:40 INFO - 'cart']}, 15:25:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'talos_options': ['--disable-e10s'], 15:25:40 INFO - 'tests': ['tp5o']}, 15:25:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'tests': ['tp5o']}, 15:25:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'talos_options': ['--disable-e10s', 15:25:40 INFO - '--xperf_path', 15:25:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:40 INFO - 'tests': ['tp5n']}, 15:25:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:40 INFO - 'talos_options': ['--xperf_path', 15:25:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:40 INFO - 'tests': ['tp5n']}}, 15:25:40 INFO - 'talos.zip': {'path': '', 15:25:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:25:40 INFO - Running post-action listener: _resource_record_post_action 15:25:40 INFO - ##### 15:25:40 INFO - ##### Finished populate-webroot step (success) 15:25:40 INFO - ##### 15:25:40 INFO - ##### 15:25:40 INFO - ##### Running create-virtualenv step. 15:25:40 INFO - ##### 15:25:40 INFO - Running pre-action listener: _resource_record_pre_action 15:25:40 INFO - Running main action method: create_virtualenv 15:25:40 INFO - Creating virtualenv /builds/slave/test/build/venv 15:25:40 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 15:25:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:25:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:25:40 INFO - Using real prefix '/usr' 15:25:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:25:40 INFO - Installing distribute.............................................................................................................................................................................................done. 15:25:42 INFO - Installing pip.................done. 15:25:42 INFO - Return code: 0 15:25:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:25:42 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')]} 15:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:42 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')]} 15:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:42 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:42 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 15:25:42 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 15:25:42 INFO - Using env: {'DISPLAY': ':0', 15:25:42 INFO - 'HOME': '/home/cltbld', 15:25:42 INFO - 'LANG': 'en_US.UTF-8', 15:25:42 INFO - 'LANGUAGE': 'en_US:en', 15:25:42 INFO - 'LOGNAME': 'cltbld', 15:25:42 INFO - 'MAIL': '/var/mail/cltbld', 15:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:42 INFO - 'MOZ_NO_REMOTE': '1', 15:25:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:42 INFO - 'NO_EM_RESTART': '1', 15:25:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:42 INFO - 'PWD': '/builds/slave/test', 15:25:42 INFO - 'SHELL': '/bin/bash', 15:25:42 INFO - 'SHLVL': '1', 15:25:42 INFO - 'TERM': 'linux', 15:25:42 INFO - 'TMOUT': '86400', 15:25:42 INFO - 'USER': 'cltbld', 15:25:42 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:42 INFO - '_': '/tools/buildbot/bin/python'} 15:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:42 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 15:25:42 INFO - Cleaning up... 15:25:42 INFO - Return code: 0 15:25:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:25:42 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')]} 15:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:42 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')]} 15:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:42 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:42 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 15:25:42 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 15:25:42 INFO - Using env: {'DISPLAY': ':0', 15:25:42 INFO - 'HOME': '/home/cltbld', 15:25:42 INFO - 'LANG': 'en_US.UTF-8', 15:25:42 INFO - 'LANGUAGE': 'en_US:en', 15:25:42 INFO - 'LOGNAME': 'cltbld', 15:25:42 INFO - 'MAIL': '/var/mail/cltbld', 15:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:42 INFO - 'MOZ_NO_REMOTE': '1', 15:25:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:42 INFO - 'NO_EM_RESTART': '1', 15:25:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:42 INFO - 'PWD': '/builds/slave/test', 15:25:42 INFO - 'SHELL': '/bin/bash', 15:25:42 INFO - 'SHLVL': '1', 15:25:42 INFO - 'TERM': 'linux', 15:25:42 INFO - 'TMOUT': '86400', 15:25:42 INFO - 'USER': 'cltbld', 15:25:42 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:42 INFO - '_': '/tools/buildbot/bin/python'} 15:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:43 INFO - Downloading/unpacking psutil>=3.1.1 15:25:43 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 15:25:43 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 15:25:43 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 15:25:43 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 15:25:43 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 15:25:43 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 15:25:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:25:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:45 INFO - Installing collected packages: psutil 15:25:45 INFO - Running setup.py install for psutil 15:25:45 INFO - building 'psutil._psutil_linux' extension 15:25:45 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 15:25:45 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 15:25:45 INFO - building 'psutil._psutil_posix' extension 15:25:45 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 15:25:45 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 15:25:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:45 INFO - Successfully installed psutil 15:25:45 INFO - Cleaning up... 15:25:45 INFO - Return code: 0 15:25:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:25:45 INFO - Using env: {'DISPLAY': ':0', 15:25:45 INFO - 'HOME': '/home/cltbld', 15:25:45 INFO - 'LANG': 'en_US.UTF-8', 15:25:45 INFO - 'LANGUAGE': 'en_US:en', 15:25:45 INFO - 'LOGNAME': 'cltbld', 15:25:45 INFO - 'MAIL': '/var/mail/cltbld', 15:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:45 INFO - 'MOZ_NO_REMOTE': '1', 15:25:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:45 INFO - 'NO_EM_RESTART': '1', 15:25:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:45 INFO - 'PWD': '/builds/slave/test', 15:25:45 INFO - 'SHELL': '/bin/bash', 15:25:45 INFO - 'SHLVL': '1', 15:25:45 INFO - 'TERM': 'linux', 15:25:45 INFO - 'TMOUT': '86400', 15:25:45 INFO - 'USER': 'cltbld', 15:25:45 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:45 INFO - '_': '/tools/buildbot/bin/python'} 15:25:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:25:45 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 15:25:45 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 15:25:45 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 15:25:45 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 15:25:45 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 15:25:45 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 15:25:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:25:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:25:47 INFO - Installing collected packages: mozsystemmonitor 15:25:47 INFO - Running setup.py install for mozsystemmonitor 15:25:47 INFO - Successfully installed mozsystemmonitor 15:25:47 INFO - Cleaning up... 15:25:47 INFO - Return code: 0 15:25:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:25:47 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')]} 15:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:47 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')]} 15:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:47 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:47 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 15:25:47 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 15:25:47 INFO - Using env: {'DISPLAY': ':0', 15:25:47 INFO - 'HOME': '/home/cltbld', 15:25:47 INFO - 'LANG': 'en_US.UTF-8', 15:25:47 INFO - 'LANGUAGE': 'en_US:en', 15:25:47 INFO - 'LOGNAME': 'cltbld', 15:25:47 INFO - 'MAIL': '/var/mail/cltbld', 15:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:47 INFO - 'MOZ_NO_REMOTE': '1', 15:25:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:47 INFO - 'NO_EM_RESTART': '1', 15:25:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:47 INFO - 'PWD': '/builds/slave/test', 15:25:47 INFO - 'SHELL': '/bin/bash', 15:25:47 INFO - 'SHLVL': '1', 15:25:47 INFO - 'TERM': 'linux', 15:25:47 INFO - 'TMOUT': '86400', 15:25:47 INFO - 'USER': 'cltbld', 15:25:47 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:47 INFO - '_': '/tools/buildbot/bin/python'} 15:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:48 INFO - Downloading/unpacking jsonschema==2.5.1 15:25:48 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 15:25:48 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 15:25:48 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 15:25:48 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 15:25:48 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 15:25:48 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 15:25:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:25:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 INFO - Downloading functools32-3.2.3-2.tar.gz 15:25:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:25:50 INFO - warning: no files found matching '*.txt' 15:25:50 INFO - no previously-included directories found matching 'build' 15:25:50 INFO - no previously-included directories found matching 'dist' 15:25:50 INFO - no previously-included directories found matching '.git*' 15:25:50 INFO - Installing collected packages: jsonschema, functools32 15:25:50 INFO - Running setup.py install for functools32 15:25:50 INFO - warning: no files found matching '*.txt' 15:25:50 INFO - no previously-included directories found matching 'build' 15:25:50 INFO - no previously-included directories found matching 'dist' 15:25:50 INFO - no previously-included directories found matching '.git*' 15:25:50 INFO - Successfully installed jsonschema functools32 15:25:50 INFO - Cleaning up... 15:25:50 INFO - Return code: 0 15:25:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:25: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')]} 15:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:50 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:50 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 15:25:50 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 15:25:50 INFO - Using env: {'DISPLAY': ':0', 15:25:50 INFO - 'HOME': '/home/cltbld', 15:25:50 INFO - 'LANG': 'en_US.UTF-8', 15:25:50 INFO - 'LANGUAGE': 'en_US:en', 15:25:50 INFO - 'LOGNAME': 'cltbld', 15:25:50 INFO - 'MAIL': '/var/mail/cltbld', 15:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:50 INFO - 'MOZ_NO_REMOTE': '1', 15:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:50 INFO - 'NO_EM_RESTART': '1', 15:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:50 INFO - 'PWD': '/builds/slave/test', 15:25:50 INFO - 'SHELL': '/bin/bash', 15:25:50 INFO - 'SHLVL': '1', 15:25:50 INFO - 'TERM': 'linux', 15:25:50 INFO - 'TMOUT': '86400', 15:25:50 INFO - 'USER': 'cltbld', 15:25:50 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:50 INFO - '_': '/tools/buildbot/bin/python'} 15:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:50 INFO - Downloading/unpacking blobuploader==1.2.4 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:25:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:25:52 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 15:25:52 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 15:25:52 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 15:25:52 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 15:25:52 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 15:25:52 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 15:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:25:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:25:53 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 15:25:53 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 15:25:53 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 15:25:53 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 15:25:53 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 15:25:53 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 15:25:53 INFO - Downloading docopt-0.6.1.tar.gz 15:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:25:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:25:53 INFO - Running setup.py install for blobuploader 15:25:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:25:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:25:53 INFO - Running setup.py install for requests 15:25:53 INFO - Running setup.py install for docopt 15:25:53 INFO - Successfully installed blobuploader requests docopt 15:25:53 INFO - Cleaning up... 15:25:54 INFO - Return code: 0 15:25:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:54 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')]} 15:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:54 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')]} 15:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:54 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:54 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 15:25:54 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 15:25:54 INFO - Using env: {'DISPLAY': ':0', 15:25:54 INFO - 'HOME': '/home/cltbld', 15:25:54 INFO - 'LANG': 'en_US.UTF-8', 15:25:54 INFO - 'LANGUAGE': 'en_US:en', 15:25:54 INFO - 'LOGNAME': 'cltbld', 15:25:54 INFO - 'MAIL': '/var/mail/cltbld', 15:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:54 INFO - 'MOZ_NO_REMOTE': '1', 15:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:54 INFO - 'NO_EM_RESTART': '1', 15:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:54 INFO - 'PWD': '/builds/slave/test', 15:25:54 INFO - 'SHELL': '/bin/bash', 15:25:54 INFO - 'SHLVL': '1', 15:25:54 INFO - 'TERM': 'linux', 15:25:54 INFO - 'TMOUT': '86400', 15:25:54 INFO - 'USER': 'cltbld', 15:25:54 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:54 INFO - '_': '/tools/buildbot/bin/python'} 15:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:54 INFO - Running setup.py (path:/tmp/pip-EbWVih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:54 INFO - Running setup.py (path:/tmp/pip-ip8B0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:54 INFO - Running setup.py (path:/tmp/pip-oWsQft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:54 INFO - Running setup.py (path:/tmp/pip-1z1mu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:54 INFO - Running setup.py (path:/tmp/pip-uorOm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:54 INFO - Running setup.py (path:/tmp/pip-wtkM40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:54 INFO - Running setup.py (path:/tmp/pip-xvUxzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:54 INFO - Running setup.py (path:/tmp/pip-De97Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:54 INFO - Running setup.py (path:/tmp/pip-_0KzMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:54 INFO - Running setup.py (path:/tmp/pip-hAiUwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:54 INFO - Running setup.py (path:/tmp/pip-5XtJ42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:55 INFO - Running setup.py (path:/tmp/pip-nYVIFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:55 INFO - Running setup.py (path:/tmp/pip-rvqcZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:55 INFO - Running setup.py (path:/tmp/pip-PLrUvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:55 INFO - Running setup.py (path:/tmp/pip-ePuLSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:55 INFO - Running setup.py (path:/tmp/pip-NoN1Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:55 INFO - Running setup.py (path:/tmp/pip-xRaYUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:25:55 INFO - Running setup.py install for manifestparser 15:25:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:25:55 INFO - Running setup.py install for mozcrash 15:25:55 INFO - Running setup.py install for mozdebug 15:25:56 INFO - Running setup.py install for mozdevice 15:25:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Running setup.py install for mozfile 15:25:56 INFO - Running setup.py install for mozhttpd 15:25:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Running setup.py install for mozinfo 15:25:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Running setup.py install for mozInstall 15:25:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Running setup.py install for mozleak 15:25:56 INFO - Running setup.py install for mozlog 15:25:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Running setup.py install for moznetwork 15:25:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:25:56 INFO - Running setup.py install for mozprocess 15:25:56 INFO - Running setup.py install for mozprofile 15:25:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:25:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:25:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:25:57 INFO - Running setup.py install for mozrunner 15:25:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:25:57 INFO - Running setup.py install for mozscreenshot 15:25:57 INFO - Running setup.py install for moztest 15:25:57 INFO - Running setup.py install for mozversion 15:25:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:25:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:25:57 INFO - Cleaning up... 15:25:57 INFO - Return code: 0 15:25:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:57 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:25:57 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 15:25:57 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 15:25:57 INFO - Using env: {'DISPLAY': ':0', 15:25:57 INFO - 'HOME': '/home/cltbld', 15:25:57 INFO - 'LANG': 'en_US.UTF-8', 15:25:57 INFO - 'LANGUAGE': 'en_US:en', 15:25:57 INFO - 'LOGNAME': 'cltbld', 15:25:57 INFO - 'MAIL': '/var/mail/cltbld', 15:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:57 INFO - 'MOZ_NO_REMOTE': '1', 15:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:57 INFO - 'NO_EM_RESTART': '1', 15:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:57 INFO - 'PWD': '/builds/slave/test', 15:25:57 INFO - 'SHELL': '/bin/bash', 15:25:57 INFO - 'SHLVL': '1', 15:25:57 INFO - 'TERM': 'linux', 15:25:57 INFO - 'TMOUT': '86400', 15:25:57 INFO - 'USER': 'cltbld', 15:25:57 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:57 INFO - '_': '/tools/buildbot/bin/python'} 15:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:57 INFO - Running setup.py (path:/tmp/pip-FICkl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:57 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)) 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:57 INFO - Running setup.py (path:/tmp/pip-wCE8OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:57 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)) 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:57 INFO - Running setup.py (path:/tmp/pip-yfvfXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:57 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)) 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:57 INFO - Running setup.py (path:/tmp/pip-GVrWFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:58 INFO - Running setup.py (path:/tmp/pip-8qr9O8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:58 INFO - Running setup.py (path:/tmp/pip-YcGqgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:58 INFO - Running setup.py (path:/tmp/pip-eVC2Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:58 INFO - Running setup.py (path:/tmp/pip-84R32U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:58 INFO - Running setup.py (path:/tmp/pip-b_jtzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:58 INFO - Running setup.py (path:/tmp/pip-Q5md_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:58 INFO - Running setup.py (path:/tmp/pip-ansddr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:58 INFO - Running setup.py (path:/tmp/pip-OATT2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:58 INFO - Running setup.py (path:/tmp/pip-A0fVwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:58 INFO - Running setup.py (path:/tmp/pip-j6qUD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:58 INFO - Running setup.py (path:/tmp/pip-_5yoNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:58 INFO - Running setup.py (path:/tmp/pip-GXvsmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:58 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)) 15:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:58 INFO - Running setup.py (path:/tmp/pip-6SPerZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:58 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)) 15:25:58 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)) 15:25:58 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)) 15:25:58 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)) 15:25:58 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)) 15:25:58 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)) 15:25:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:25:58 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 15:25:58 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 15:25:58 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 15:25:58 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 15:25:58 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 15:25:58 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 15:26:00 INFO - Downloading blessings-1.6.tar.gz 15:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:26:00 INFO - Installing collected packages: blessings 15:26:00 INFO - Running setup.py install for blessings 15:26:01 INFO - Successfully installed blessings 15:26:01 INFO - Cleaning up... 15:26:01 INFO - Return code: 0 15:26:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:01 INFO - Reading from file tmpfile_stdout 15:26:01 INFO - Current package versions: 15:26:01 INFO - argparse == 1.2.1 15:26:01 INFO - blessings == 1.6 15:26:01 INFO - blobuploader == 1.2.4 15:26:01 INFO - docopt == 0.6.1 15:26:01 INFO - functools32 == 3.2.3-2 15:26:01 INFO - jsonschema == 2.5.1 15:26:01 INFO - manifestparser == 1.1 15:26:01 INFO - mozInstall == 1.12 15:26:01 INFO - mozcrash == 0.17 15:26:01 INFO - mozdebug == 0.1 15:26:01 INFO - mozdevice == 0.48 15:26:01 INFO - mozfile == 1.2 15:26:01 INFO - mozhttpd == 0.7 15:26:01 INFO - mozinfo == 0.9 15:26:01 INFO - mozleak == 0.1 15:26:01 INFO - mozlog == 3.2 15:26:01 INFO - moznetwork == 0.27 15:26:01 INFO - mozprocess == 0.23 15:26:01 INFO - mozprofile == 0.28 15:26:01 INFO - mozrunner == 6.12 15:26:01 INFO - mozscreenshot == 0.1 15:26:01 INFO - mozsystemmonitor == 0.3 15:26:01 INFO - moztest == 0.7 15:26:01 INFO - mozversion == 1.4 15:26:01 INFO - psutil == 3.1.1 15:26:01 INFO - requests == 1.2.3 15:26:01 INFO - wsgiref == 0.1.2 15:26:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26: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')]} 15:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:01 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 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:26:01 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 15:26:01 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 15:26:01 INFO - Using env: {'DISPLAY': ':0', 15:26:01 INFO - 'HOME': '/home/cltbld', 15:26:01 INFO - 'LANG': 'en_US.UTF-8', 15:26:01 INFO - 'LANGUAGE': 'en_US:en', 15:26:01 INFO - 'LOGNAME': 'cltbld', 15:26:01 INFO - 'MAIL': '/var/mail/cltbld', 15:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:01 INFO - 'MOZ_NO_REMOTE': '1', 15:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:01 INFO - 'NO_EM_RESTART': '1', 15:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:01 INFO - 'PWD': '/builds/slave/test', 15:26:01 INFO - 'SHELL': '/bin/bash', 15:26:01 INFO - 'SHLVL': '1', 15:26:01 INFO - 'TERM': 'linux', 15:26:01 INFO - 'TMOUT': '86400', 15:26:01 INFO - 'USER': 'cltbld', 15:26:01 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:01 INFO - '_': '/tools/buildbot/bin/python'} 15:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 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)) 15:26:01 INFO - Cleaning up... 15:26:01 INFO - Return code: 0 15:26:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:26: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')]} 15:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, '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': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', '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 15:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:26:01 INFO - Using env: {'DISPLAY': ':0', 15:26:01 INFO - 'HOME': '/home/cltbld', 15:26:01 INFO - 'LANG': 'en_US.UTF-8', 15:26:01 INFO - 'LANGUAGE': 'en_US:en', 15:26:01 INFO - 'LOGNAME': 'cltbld', 15:26:01 INFO - 'MAIL': '/var/mail/cltbld', 15:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:01 INFO - 'MOZ_NO_REMOTE': '1', 15:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:01 INFO - 'NO_EM_RESTART': '1', 15:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:01 INFO - 'PWD': '/builds/slave/test', 15:26:01 INFO - 'SHELL': '/bin/bash', 15:26:01 INFO - 'SHLVL': '1', 15:26:01 INFO - 'TERM': 'linux', 15:26:01 INFO - 'TMOUT': '86400', 15:26:01 INFO - 'USER': 'cltbld', 15:26:01 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:01 INFO - '_': '/tools/buildbot/bin/python'} 15:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:26:01 INFO - Cleaning up... 15:26:01 INFO - Return code: 0 15:26:01 INFO - Running post-action listener: _resource_record_post_action 15:26:01 INFO - Running post-action listener: _start_resource_monitoring 15:26:01 INFO - Starting resource monitoring. 15:26:01 INFO - ##### 15:26:01 INFO - ##### Finished create-virtualenv step (success) 15:26:01 INFO - ##### 15:26:01 INFO - ##### 15:26:01 INFO - ##### Running install step. 15:26:01 INFO - ##### 15:26:01 INFO - Running pre-action listener: _resource_record_pre_action 15:26:01 INFO - Running main action method: install 15:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:02 INFO - Reading from file tmpfile_stdout 15:26:02 INFO - Detecting whether we're running mozinstall >=1.0... 15:26:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:26:02 INFO - Reading from file tmpfile_stdout 15:26:02 INFO - Output received: 15:26:02 INFO - Usage: mozinstall [options] installer 15:26:02 INFO - Options: 15:26:02 INFO - -h, --help show this help message and exit 15:26:02 INFO - -d DEST, --destination=DEST 15:26:02 INFO - Directory to install application into. [default: 15:26:02 INFO - "/builds/slave/test"] 15:26:02 INFO - --app=APP Application being installed. [default: firefox] 15:26:02 INFO - mkdir: /builds/slave/test/build/application 15:26:02 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'] 15:26:02 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 15:26:25 INFO - Reading from file tmpfile_stdout 15:26:28 INFO - Output received: 15:26:28 INFO - /builds/slave/test/build/application/firefox/firefox 15:26:28 INFO - Running post-action listener: _resource_record_post_action 15:26:28 INFO - ##### 15:26:28 INFO - ##### Finished install step (success) 15:26:28 INFO - ##### 15:26:28 INFO - ##### 15:26:28 INFO - ##### Running run-tests step. 15:26:28 INFO - ##### 15:26:28 INFO - Running pre-action listener: _resource_record_pre_action 15:26:28 INFO - Running main action method: run_tests 15:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:26:28 INFO - Python 2.7.3 15:26:28 INFO - Return code: 0 15:26:28 INFO - grabbing minidump binary from tooltool 15:26: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')]} 15:26:28 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 0x27963b0>, '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 0x27972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2797740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:26:28 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 15:26:28 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 15:26:28 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 15:26:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:26:28 INFO - Return code: 0 15:26:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:26:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:26:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:26:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:26:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 15:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 15:26:28 INFO - Using env: {'DISPLAY': ':0', 15:26:28 INFO - 'HOME': '/home/cltbld', 15:26:28 INFO - 'LANG': 'en_US.UTF-8', 15:26:28 INFO - 'LANGUAGE': 'en_US:en', 15:26:28 INFO - 'LOGNAME': 'cltbld', 15:26:28 INFO - 'MAIL': '/var/mail/cltbld', 15:26:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:26:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:28 INFO - 'MOZ_NO_REMOTE': '1', 15:26:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:26:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:28 INFO - 'NO_EM_RESTART': '1', 15:26:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:28 INFO - 'PWD': '/builds/slave/test', 15:26:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:26:28 INFO - 'SHELL': '/bin/bash', 15:26:28 INFO - 'SHLVL': '1', 15:26:28 INFO - 'TERM': 'linux', 15:26:28 INFO - 'TMOUT': '86400', 15:26:28 INFO - 'USER': 'cltbld', 15:26:28 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905', 15:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:28 INFO - '_': '/tools/buildbot/bin/python'} 15:26:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 15:26:28 INFO - mozversion application_buildid: 20160718124149 15:26:28 INFO - mozversion application_changeset: 2a69345f4f4eeb804e6a96c70f086f10e9849724 15:26:28 INFO - mozversion application_display_name: Nightly 15:26:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:26:28 INFO - mozversion application_name: Firefox 15:26:28 INFO - mozversion application_remotingname: firefox 15:26:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:26:28 INFO - mozversion application_vendor: Mozilla 15:26:28 INFO - mozversion application_version: 50.0a1 15:26:28 INFO - mozversion platform_buildid: 20160718124149 15:26:28 INFO - mozversion platform_changeset: 2a69345f4f4eeb804e6a96c70f086f10e9849724 15:26:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:26:28 INFO - mozversion platform_version: 50.0a1 15:26:28 INFO - using testdate: 1468880788 15:26:28 INFO - actual date: 1468880788 15:26:28 INFO - starting webserver on 'localhost:58932' 15:26:28 INFO - SUITE-START | Running 2 tests 15:26:28 INFO - TEST-START | dromaeo_css 15:26:28 INFO - operating with platform_type : linux_ 15:26:28 INFO - Initialising browser for dromaeo_css test... 15:26:28 INFO - TEST-INFO | started process 1529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiykpqd/profile http://localhost:58932/getInfo.html) 15:26:35 INFO - PROCESS | 1529 | __metrics Screen width/height:1600/1200 15:26:35 INFO - PROCESS | 1529 | colorDepth:24 15:26:35 INFO - PROCESS | 1529 | Browser inner width/height: 1024/697 15:26:35 INFO - PROCESS | 1529 | __metrics 15:26:38 INFO - PROCESS | 1529 | 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" 15:26:38 INFO - PROCESS | 1529 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:26:39 INFO - PROCESS | 1529 | console.error: PushService: 15:26:39 INFO - PROCESS | 1529 | stateChangeProcessEnqueue: Error transitioning state 15:26:39 INFO - PROCESS | 1529 | UnknownError 15:26:39 INFO - TEST-INFO | 1529: exit 0 15:26:39 INFO - Browser initialized. 15:26:39 INFO - Running cycle 1/1 for dromaeo_css test... 15:26:39 INFO - TEST-INFO | started process 1613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiykpqd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:51 INFO - PROCESS | 1613 | RSS: Main: 157605888 15:26:51 INFO - PROCESS | 1613 | 15:27:43 INFO - PROCESS | 1613 | 1468880863159 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] 15:29:51 INFO - PROCESS | 1613 | Cycle 1(1): loaded http://localhost:58932/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58932/tests/dromaeo/cssquery-ext.html) 15:29:52 INFO - PROCESS | 1613 | RSS: Main: 254898176 15:29:52 INFO - PROCESS | 1613 | 15:32:55 INFO - PROCESS | 1613 | Cycle 1(1): loaded http://localhost:58932/tests/dromaeo/cssquery-ext.html (next: http://localhost:58932/tests/dromaeo/cssquery-jquery.html) 15:32:55 INFO - PROCESS | 1613 | RSS: Main: 242307072 15:32:55 INFO - PROCESS | 1613 | 15:35:57 INFO - PROCESS | 1613 | Cycle 1(1): loaded http://localhost:58932/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58932/tests/dromaeo/cssquery-mootools.html) 15:35:58 INFO - PROCESS | 1613 | RSS: Main: 238223360 15:35:58 INFO - PROCESS | 1613 | 15:39:00 INFO - PROCESS | 1613 | Cycle 1(1): loaded http://localhost:58932/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58932/tests/dromaeo/cssquery-prototype.html) 15:39:01 INFO - PROCESS | 1613 | RSS: Main: 236396544 15:39:01 INFO - PROCESS | 1613 | 15:42:03 INFO - PROCESS | 1613 | Cycle 1(1): loaded http://localhost:58932/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58932/tests/dromaeo/cssquery-yui.html) 15:42:04 INFO - PROCESS | 1613 | RSS: Main: 223252480 15:42:04 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | Cycle 1(1): loaded http://localhost:58932/tests/dromaeo/cssquery-yui.html (next: http://localhost:58932/tests/dromaeo/cssquery-dojo.html) 15:45:07 INFO - PROCESS | 1613 | RSS: Main: 283463680 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | __start_tp_report 15:45:07 INFO - PROCESS | 1613 | _x_x_mozilla_page_load 15:45:07 INFO - PROCESS | 1613 | _x_x_mozilla_page_load_details 15:45:07 INFO - PROCESS | 1613 | |i|pagename|runs| 15:45:07 INFO - PROCESS | 1613 | |0;dojo.html;3042.0873737878646;3044.2693753499157;3050.7254347108765;3090.474619314716;3093.149383919423;5338.839834804952;5386.838394848151;5429.375621803484;5450.236966824643;5494.670319780811;5404.540614047801;5431;5451.3458384993855;5465.453454654533;5514.503694667479;4867.513248675131;4930.975345123269;4964.081644895706;4964.87587810304;5126.307948426952;5025.422076461217;5064.417591976933;5077.543021128097;5090.770915308802;5093.617978651616;4850.8059677612855;4887.0714564232685;4893.926591101136;4921.7785199666105;5045.621578381636;4821.108095002436;4828.855134345975;4833.250846118857;4901.754912254409;4950.653454258203;4876.073546026244;4876.439209490918;4895.241237608141;4898.975505122487;4909.803607855682;5370.812021579265;5412.431694672042;5476.479734425223;5519.586031047647;5535.059039963176;5867.383924687889;5889.528837692974;5900.822975310704;5916.497097746654;5919.052951527778;5747.741351639186;5930.733117009745;5935.4361335673475;5945.078512830518;5947.672877991708;5412.621116521845;5469.972650136724;5481.561475081984;5565.053940830109;5571.804986825442;6027.035674292092;6041.909371359433;6053.576249662569;6067;6068.9696551517845;5570.693611851387;5594.972025139929;5843.2403787507355;5851.239338885917;5854.912176317359;5688.232088667977;5788;5792.88414231713;5843;5843.094320380263;5682.374938756733;5735.684537350486;5739.053056245756;5740.655560666373;5769.048107062287;5676.489115979582;5693.430656934273;5722.055860783007;5752.6836024019085;5756.280464941882;4374.256376416017;4383.60547550722;4387;4421.80101895409;4431.955680443219;3906.5898080701118;3916.7649941003633;3921.8627348042683;3951.565327813938;3953.1105501261986;5371.033214021436;5403.567714582824;5438.129899216106;5469.2616496772425;5511.751971161308;5871.500922421642;5891.3224979127945;5903.881922361528;5904.881902361929;5936.079907614327;5559.305086864142;5566.777328906879;5573.888522229533;5579.302587176603;5579.4420557944695;4739.549742774431;4829.203181475017;4832.830850920201;4835.709857408497;4909.558139767438;5971.701414929236;6009.308929473079;6011.368806275365;6014.1580178775;6032.577719559588;5835.4748072673665;5858.648481091148;5863;5885.47030767233;5885.793997210077;5877.617954832922;5925.466707996301;5962.5528085394635;5974.820755377345;6038.63768173911;5726.74229659658;5773.191753154478;5776.046952252831;5796.1305804129715;5806.187133801187;5620.74706638194;5853.2976042875125;5854.356020837619;5880.676562789087;5890.263717035371;5647.209390685225;5703.315602127605;5704.914426283526;5710.971445142748;5712.08606622955;5698.287714035746;5701.572382071444;5704.543636509153;5705.714714264187;5706.9714651426475;5512.6692398456225;5533.972330138324;5642.492175704207;5652.63257888236;5660.4905558498285;5675.035244008446;5701.971490142523;5719.456651618089;5776.480116789509;5778.162166485752;5539.667619942817;5584.916226256691;5587.804426845041;5589.105743081088;5592.105263157875 15:45:07 INFO - PROCESS | 1613 | |1;ext.html;598.7784519727701;603.9305479869815;614.1616693213759;620.1411045701705;628.867937733076;33403;36143;36241.63758362416;36271.637283627155;36304.63695363049;36419.81790091046;36685.44971825425;44690.55309446905;45865.770671146594;46245.768771156094;9078.636854525828;9191.95404022979;9192.816143677122;9212.078792120801;9224.077592240756;8803.20771130598;8887.377883548153;8894.199522043047;8895.510746908918;8899.421537600068;8945.105489451036;8965.506897120657;8976.775580610472;8978.371513994023;8979.730608081736;8893.288536917064;8910.821783564292;8943.329250306253;8946.015938246788;8946.910530894673;9104.453732776055;9165.58754856033;9171.495567743772;9172.908270917338;9173.862392064124;43629.12741745148;43892.341614875804;43994.12011759779;44029;44056.559434405564;3532.452469867175;3646.161382881952;4110.753354798721;4124.319487284594;4149.605787450188;33544.6645533544;34400;34765;34792.47811282833;34819.651803481895;4195.853145139906;4249.553796851317;4338.392625032498;4376.846810361622;4467.932981005288;358595;358790.2060489707;358932;359189;359521;60360;60892;61256.38743612596;61269.38730612726;61382.69308653428;63492.682536587025;63705.68147159235;63751.68124159442;63850.68074659597;63895.680521597096;15596.688066238612;15784.289706963214;15794.9210253948;15795.526134215992;15797.921010395105;12073.517059317528;12104.394780261127;12123;12124.454399552042;12131.090168237417;17243.224054917308;18107.18517666708;18187;18220.27118915255;18273.26906923708;5917.171595976567;6090.908636370458;6091.086337049478;6171.13604095427;6177.598456100339;925.3383830561047;991.4695637833725;1035.2856528994969;1058.2010582010532;1081.7187531241934;33036;33375.83312083472;33443;33657.663423365455;34335.313293734485;961.7451375385529;1101.372217835826;1108.6784832398675;1189.0665827325508;1197.4671271284194;16661.583460413585;17444.302227910743;17944.91027544854;18031.668235633966;18866.81133188678;525570;533011;535460;536555;537647;59743.40256597378;60187;60237;60278.69860650669;60300.69849650723;1251.787196176652;1346.7037251804588;1392.7980442835935;1419.283261952728;1420.6803469219342;33179.33641327208;34480.13799654928;34634.48048279227;34679.479807802396;34682;51614.483855160965;51674.22488662748;52262.21606675979;52384.214236787244;52413.47586524086;324705;324974;325578;326580;326966;25007.624885627094;28211.57682634721;28586.42827143487;28634.14097577076;30193.547096793125;48776.75611621919;49426.25860612022;49494;50618;50629.49370506248;40973.38539921844;41490.37764433596;41947;42796.57203428051;43351;41899.37150942799;42128;42502.78748606237;43002.1399572013;43096.13807723891;41788.3731744018;42464;42968;43170.784146079066;43187;35641.64358356383;37318.25363492769;37648.43527347037;38070.61929380671;38493.615063849;28426.43147137087;28769.85615071911;28796.42407151887;28813.71186288194;28935.421291574472 15:45:07 INFO - PROCESS | 1613 | |2;jquery.html;376.8398430666966;382.0515371604815;383.0385731813148;383.5589072566549;401.76697515441026;19444.51388715281;19563.5109122272;20391.184352625896;20408.59182816343;20441.591168176645;18992.810071899275;19465.902670486663;19720.40838774839;19755.703664445045;19762.30831920883;5236;5561.555075593963;5643.266375371196;5732.48407643312;5767.163761254616;5594.049011668027;5647.29408823897;5649.12438572022;5669.206311116447;5669.404712505189;5577.972110139443;5608.523275521586;5618.5786066045;5858.5606079543995;5858.853528661796;5873.177755114288;5875.38308477608;5879.265091863517;5890.617109887865;5914.2902851657645;5432.945670543283;5632.098864181711;5638.633488823213;5639.8590035249035;5640.238567793377;13094;13220.735585288337;13226;13377.799333010013;13492.190468571916;2228.186711850163;2348.0842471436154;2357.71707395112;2644.2728249731476;2759.2550011496983;9294.024127466586;9299.72100836976;9375.484348360837;9404.90595094047;9795.706128816148;1856.2760523395887;1864.4872660018602;1876.5777700017522;1919.5680971781378;1946.8150525700037;60923;83925;84192.57903710504;84644.57677711573;86752.56623716842;9656.37233579815;9983.151432128205;10091.44497052669;10112.089911907817;10153.340032897839;13181.209127452383;13720.725585488433;14392.56822295312;14433.494827680981;14453.78319325211;7447.664855081534;7514.4364172687265;7520.059992500937;7646.923530764621;7755.806104847311;7199.856002879913;7240.094988126484;7262.600556969312;7414.962925185339;7454.664540095709;4397.1865204937185;4466.486354069259;4554.4990051094355;4560.977195114003;4576.313552967082;1845.6954602490707;1879.9060046997595;1909.4653497020856;1928.3154480159621;1932.9130189141522;992.5583115513672;1007.6573964851985;1043.7755882485303;1078.6820972605133;1079.7354648111107;18869.245230190638;19288.80711192898;19293.131809068625;19391;19460.51348716265;937.5734041011324;991.504247876062;992.6724181020246;1000.3747657713928;1011.4588695048114;10124.493775311204;10617.946910265553;10655.668631309616;10724.946375268073;10778.676639700821;510257;516234;521481;530380;549055;93668;93827.53086234526;98390.50804746074;99187;102901.48549257356;1022.7136401807505;1029.7837454134715;1033.379972016784;1043.1289872956168;1057.5822550092823;16798.24407901623;16914.154292285584;16923.407680731114;17112.315507379564;17185.57036074058;41174;42431.7878410606;42776.78611606922;43330.78334608433;45286.320705190104;58054.4194558049;59649.70175149097;64621;66318.00522992255;67187.66406167937;7819.178986206366;8129.959350203448;8222.876656850032;8286.596886902731;8434.156584341517;9367.250619950251;12493.93753031229;12772.936135319263;12910.935445322713;12949;11172.832407514057;11283.774324513299;11317.264377815416;11898.702532436759;12035.45840437165;11139.888601113886;11245.325280483197;11287.435628218393;11344.716382090513;11402.885971140182;10548.103411209826;11663.183577149857;11715.707107322045;11848.703782405164;11961.282323060645;7039.260877607982;7644.426667999811;7667.961660191663;7812.140664527011;8509.149085091442;10632;11264.267822591506;11466.31202127875;11618.128640352177;11804 15:45:07 INFO - PROCESS | 1613 | |3;mootools.html;1336.4453751693047;1468.537410715625;1490.8732757715595;1637.6069743261614;1778.5731424458133;8498.362622803292;8886.20024197822;8894.155055269755;9131.817363652723;9144.90855091449;9021.097890210976;9204.447733136;9204.723858284246;9206.49364284964;9210.032946540618;2033.1359172351765;2299.632058870581;2338.1582630253138;2373.7982271506858;2378.0963233971065;2383.4875501767115;2386.59427897257;2387.9641805372935;2390.2112302940077;2391.3184742348435;2300.275413244831;2331.1258278145697;2332.9883350583223;2333.0434521846046;2333.3349995251356;1976.9703454448195;2204.7684993075654;2276.3057267533395;2303.2053941390195;2306.1351993002622;2289.5993201189726;2312.6415405612156;2337.111897478947;2342.566625174331;2344.402177444758;8804.603792829337;8949.507777072258;8949.776255593662;8983.955080224623;9082.273418126533;5099.617528685327;5233.633645644806;5254.65844720092;5270.341207349082;5270.947290527122;3194.6006749156354;3351.514030465569;3387.745919056056;3407.2333724911946;3420.059483642018;1036.0623635609786;1077.154433769495;1108.839218313348;1113.6325012745774;1116.179607988128;12305.569305074368;12441.191322564095;12495.125341226118;12501;12599.3070381129;4459.531749166355;4463.776811159429;4643.535646435329;4646.976765116153;4705.341252224691;10561.841572376421;10580.312279701795;10591.887851775453;10624.734381640521;10668.30656007357;5155.53600175986;5215.061288967946;5219.556337711261;5226.529612334908;5392.137258038695;4182.079942412664;4717.646176536773;4854.78153483087;4893.926591101136;4968.577670897942;3894.4353068804867;4042.535108462506;4102.323116685773;4107.301758700968;4156.9792151039055;1135.7387800805861;1231.950721971129;1232.1128787273149;1241.875812418751;1244.8506179258547;849.668629234599;850.953197574127;905.0180554098745;909.7179874239007;942.707760594218;5775.8556036099435;5975.282966044103;6016.2479690038745;6078.483328917003;6087.847803804941;463.7495752293791;640.5804198250154;1023.4831410137981;1034.4775888176573;1040.2614143957724;3844.557875844258;3946.092398748304;4000.0599859033105;4003.1193137510286;4088.325426304686;10978.945105274583;10981.231313807957;11131.053860421793;11140.331580105058;11153.609623663133;10805.243632945618;10956.233063685624;10991;11020.283681560833;11107.166962477846;8471.449355791978;8610.397272191012;8615.181557752003;8618.784530386665;8628.784280392914;5783.7397317119985;5784.392638772868;5823.359430462561;5836.737346819488;5887;10391.948040259751;10591.364518128938;10654.946725266323;10656.573737050741;10676.412797296223;10622.84065738999;10731.946340268249;10974.615888443866;11042.613508527164;11046.502907369028;6893.276205998298;7537.434692398202;7726.497777644435;7779.066512018594;7894.526328420313;9099.1810737034;9120.817583648422;9163.679271225476;9184.816303673755;9188.035256297993;7598.582077985764;7641.579713115832;7727.961360193163;7755.495892766952;8007.239312265326;7627.199144090013;7666.616669166631;7772;7788.182240864628;7875.054993400829;7251.8187045324285;7264.309890560388;7293.088363954505;7298.306660867209;7315.926840731525;4854.975725121493;5047.722375269395;5074.467180945948;5154.664946778447;5175.249588809676;5029.673071250357;5099.745012749422;5139.948600513947;5173.249878767633;5207.71357575337 15:45:07 INFO - PROCESS | 1613 | |4;prototype.html;316.07863079124337;317.8744395963594;318.5969748268441;322.85632893362464;324.263920899558;5271.578273738101;5289.576833853293;5296.364436267649;5297.629165958385;5300.985625462328;5235.214717792333;5236.895262094766;5263.4210236874005;5266.420693723688;5267.341582302212;3948.60513948605;4001.679865610744;4049.3116170250983;4051.6150965658217;4066.3900414937702;3953.5058117735284;4133.503979522461;4136.710430269883;4139.234241665286;4146.942529654933;4052.29084910141;4082.7958602069925;4087.448194493735;4089.8568550100754;4102.384642303663;3932.508436445444;4024.979875100635;4098.692598055158;4101.138760860223;4106.7946602670045;3528.2237907660274;3560.608333083359;3565.893023209308;3620;3634.4548317752283;5090.541851233407;5094.566961808214;5108.66793658411;5118.923216151761;5132.666376685504;4546.40896683429;4664.720116793003;4667.603253723438;4673.228917228653;4676.50896655853;5421.316448358049;5428.240046393508;5431.402545719968;5438.564914806845;5441.727913604304;3960.3663413853646;3960.683145348365;3963.346047902093;3963.801809909493;3964.207158568298;5223.764930578133;5552.639078459928;5639.577031722596;5651.265335506358;5656.4060773618585;3964.980175099106;4004.6796256299426;4017.7902153815285;4023.8189281482405;4034.8184331705143;4017.8593749218635;4019.2966230909474;4030.536488303824;4065.5527891931865;4073.2668119738137;4427.335899615084;4641.930371044437;4643.44278686553;4654.790534425959;4658.718852315573;5501.504864562208;5502.862428439321;5520.447955204448;5523.7790488380815;5530.949119667331;1392.6379141423304;1409.5137177673687;1410.619132834129;1411.6047506698142;1413.2721648351107;898.3038145437208;914.6890057380523;926.6941909169958;953.6233187890745;962.7159987803615;5354.170103633943;5379.838604841861;5388.811391601275;5392.703401312965;5404.32445944257;5592.552595792326;5595.300587426572;5608.10270356741;5691.943080569225;5723.7996670116345;5114.386273647112;5245.737713114406;5326.44072372403;5327.760250788724;5367.946320536823;3003.8585337571585;3005.128512731327;3020.154356780105;3030.1515575638855;3031.681673424303;5502.697351645698;5822.679752613562;5828.504577110908;5837.883242335214;5848.912266316009;5681.886362272731;5737.799177028784;5740.368559458456;5749.511291540182;5798.275215598051;5745.597808153389;5754.913676294859;5772.5959182856795;5787.623804452782;5796.246487956623;5419.593530485308;5576.275084239023;5608.298962629671;5622.7469763861545;5638.971805141112;5476.7535460903555;5502.431806935005;5507.915567282334;5532.28080349552;5533.197686335378;5508.779648814003;5601.767611125546;5852.122181672783;5862.530997520274;5876.500497457764;4007.6793856490826;4007.899802504961;4016.5380981187536;4022.517297924268;4027.8791636250958;5372.62391632582;5627.606067575231;5737.540996720169;5761.48146666802;5770;5569.66582005081;5578.693171875423;5615.410381910004;5635.267415235993;5644.435556444323;5585.301837270341;5601.971990140023;5604.299462567179;5654.547636189012;5667.801626943037;5247.2684014275965;5503.083365816124;5515.007298686275;5516.227728118147;5529.0924631002235;5594.244776955059;5595.63628364155;5603.691796951207;5607.271054762855;5616.915746263891;5484.327280179424;5492.5331346835965;5499.725013749377;5509.256250406304;5513.2005859151 15:45:07 INFO - PROCESS | 1613 | |5;yui.html;744.6946751831748;766.2720415605174;768.7424712721238;839.8278352937645;863.6415887406727;15803.841961580381;17150.313987440488;17183.82816171838;17208.39770608029;17273.395431159897;15423;15726.528204153865;15736.370545178179;15760.211989400541;15804.209789510507;666.3503084492618;789.6091434739811;791.2522666080556;795.3796039089519;825.3149885594942;822.4571782623469;824.4146655874337;828.904675962263;829.6391069884589;829.9419040667156;710.8080818179088;883.0154377868662;884.5356188001318;885.1237275097671;885.4598694796169;840.8528507511161;863.386995233385;865.126222515261;867.0332579174238;868.3617541107282;642.1299139665732;642.6401215319389;642.7975187815823;642.8328634555003;643.1221382812428;14762.852371476256;14782.334794934146;14792.704145917129;14926.776098358425;15010.69978600422;13717;13768.967327450482;13822.447102115902;13829.79255311171;14156.92921535396;12400.317982510958;12465.75068498625;12578.496860125584;13001.609951701465;13069.346532673424;4111.259973204822;4163.271427500175;4244.639205667522;4284.078923031563;4374.628156606692;75227;75379.24620753777;76212.61893690552;76347.99999999945;76369.61815190944;11117.610883619034;11252.381119038368;11262.943685281522;11270.94364528172;11500;20632.793672063388;20648.690269645966;20714;20736.377908662766;20753.896230518752;7043.119610048744;7115.786526404217;7361.484696071224;7516.47384683067;7751.689932402641;4357.8725676612885;4402.779861006937;4479.664025198123;4481.574250446202;4535.7732113394195;3313.7514686398617;3432.2792213634916;3434.6737059979264;3440.82795860206;3445.3109378124477;610.0452791381474;657.8881590129689;663.3167837127767;684.9589024658536;719.4064896460441;660.5112216959416;725.8983742276085;729.9708011679579;738.7340557399332;762.1920763990188;9204.40171388871;9433.858492122623;9965.501724913725;10025.899741002642;10231.429954632318;629.1852051593198;650.902364645307;651.824007517977;656.6815094679076;728.2535401213798;4941.456439791692;5095.210242412432;5102.438731739506;5106.234064890222;5106.770195341219;77949.61025194838;91312;134719;138635;140061;68593.31406685969;69913.65043174752;72561;112432.87567124225;114559;98326;100410.4979475098;100829.49585252174;103001.48499257605;103069.48465257775;9212.170904618617;9708.85436718464;9860.753481163028;9955.20358371343;10023.398596084258;58381.416185837596;58653.70673146607;59299.70350148222;59446.405535945814;60535.69732151311;67100;85417;86964;89988;90470;13040.282784446947;13803;13893.652658683613;14251.714965700836;14614.780778288547;14403.664255623826;15258.549578111455;16044.171803598758;16186.271617776993;16931.32274708998;13637.522686705917;13730.107543009672;13755.10591811569;13890.722185556435;13975.95652412585;14743.778843317144;14924.85075149278;15187.164705941279;15328.233588320762;15335.846641533442;13986;14062.789058163931;14380.065295755743;14613.19627420502;14849.257537123316;11721.12091593117;12094.697632558904;12229.266244025386;12282.938585307375;12297;11014.834777478505;11063.612773552886;11225.775484490428;11338.943305283421;11582.6525204244 15:45:07 INFO - PROCESS | 1613 | __end_tp_report 15:45:07 INFO - PROCESS | 1613 | __start_cc_report 15:45:07 INFO - PROCESS | 1613 | _x_x_mozilla_cycle_collect,849 15:45:07 INFO - PROCESS | 1613 | __end_cc_report 15:45:07 INFO - PROCESS | 1613 | __startTimestamp1468881907589__endTimestamp 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | ------- Summary: start ------- 15:45:07 INFO - PROCESS | 1613 | Number of tests: 6 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | [#0] dojo.html Cycles:165 Average:5401.93 Median:5592.11 stddev:638.58 (11.4%) stddev-sans-first:613.12 15:45:07 INFO - PROCESS | 1613 | Values: 3042.1 3044.3 3050.7 3090.5 3093.1 5338.8 5386.8 5429.4 5450.2 5494.7 5404.5 5431.0 5451.3 5465.5 5514.5 4867.5 4931.0 4964.1 4964.9 5126.3 5025.4 5064.4 5077.5 5090.8 5093.6 4850.8 4887.1 4893.9 4921.8 5045.6 4821.1 4828.9 4833.3 4901.8 4950.7 4876.1 4876.4 4895.2 4899.0 4909.8 5370.8 5412.4 5476.5 5519.6 5535.1 5867.4 5889.5 5900.8 5916.5 5919.1 5747.7 5930.7 5935.4 5945.1 5947.7 5412.6 5470.0 5481.6 5565.1 5571.8 6027.0 6041.9 6053.6 6067.0 6069.0 5570.7 5595.0 5843.2 5851.2 5854.9 5688.2 5788.0 5792.9 5843.0 5843.1 5682.4 5735.7 5739.1 5740.7 5769.0 5676.5 5693.4 5722.1 5752.7 5756.3 4374.3 4383.6 4387.0 4421.8 4432.0 3906.6 3916.8 3921.9 3951.6 3953.1 5371.0 5403.6 5438.1 5469.3 5511.8 5871.5 5891.3 5903.9 5904.9 5936.1 5559.3 5566.8 5573.9 5579.3 5579.4 4739.5 4829.2 4832.8 4835.7 4909.6 5971.7 6009.3 6011.4 6014.2 6032.6 5835.5 5858.6 5863.0 5885.5 5885.8 5877.6 5925.5 5962.6 5974.8 6038.6 5726.7 5773.2 5776.0 5796.1 5806.2 5620.7 5853.3 5854.4 5880.7 5890.3 5647.2 5703.3 5704.9 5711.0 5712.1 5698.3 5701.6 5704.5 5705.7 5707.0 5512.7 5534.0 5642.5 5652.6 5660.5 5675.0 5702.0 5719.5 5776.5 5778.2 5539.7 5584.9 5587.8 5589.1 5592.1 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | [#1] ext.html Cycles:180 Average:57722.37 Median:42296.00 stddev:110216.06 (260.6%) stddev-sans-first:110441.79 15:45:07 INFO - PROCESS | 1613 | Values: 598.8 603.9 614.2 620.1 628.9 33403.0 36143.0 36241.6 36271.6 36304.6 36419.8 36685.4 44690.6 45865.8 46245.8 9078.6 9192.0 9192.8 9212.1 9224.1 8803.2 8887.4 8894.2 8895.5 8899.4 8945.1 8965.5 8976.8 8978.4 8979.7 8893.3 8910.8 8943.3 8946.0 8946.9 9104.5 9165.6 9171.5 9172.9 9173.9 43629.1 43892.3 43994.1 44029.0 44056.6 3532.5 3646.2 4110.8 4124.3 4149.6 33544.7 34400.0 34765.0 34792.5 34819.7 4195.9 4249.6 4338.4 4376.8 4467.9 358595.0 358790.2 358932.0 359189.0 359521.0 60360.0 60892.0 61256.4 61269.4 61382.7 63492.7 63705.7 63751.7 63850.7 63895.7 15596.7 15784.3 15794.9 15795.5 15797.9 12073.5 12104.4 12123.0 12124.5 12131.1 17243.2 18107.2 18187.0 18220.3 18273.3 5917.2 6090.9 6091.1 6171.1 6177.6 925.3 991.5 1035.3 1058.2 1081.7 33036.0 33375.8 33443.0 33657.7 34335.3 961.7 1101.4 1108.7 1189.1 1197.5 16661.6 17444.3 17944.9 18031.7 18866.8 525570.0 533011.0 535460.0 536555.0 537647.0 59743.4 60187.0 60237.0 60278.7 60300.7 1251.8 1346.7 1392.8 1419.3 1420.7 33179.3 34480.1 34634.5 34679.5 34682.0 51614.5 51674.2 52262.2 52384.2 52413.5 324705.0 324974.0 325578.0 326580.0 326966.0 25007.6 28211.6 28586.4 28634.1 30193.5 48776.8 49426.3 49494.0 50618.0 50629.5 40973.4 41490.4 41947.0 42796.6 43351.0 41899.4 42128.0 42502.8 43002.1 43096.1 41788.4 42464.0 42968.0 43170.8 43187.0 35641.6 37318.3 37648.4 38070.6 38493.6 28426.4 28769.9 28796.4 28813.7 28935.4 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | [#2] jquery.html Cycles:180 Average:29827.06 Median:382.55 stddev:86749.22 (22676.9%) stddev-sans-first:86964.39 15:45:07 INFO - PROCESS | 1613 | Values: 376.8 382.1 383.0 383.6 401.8 19444.5 19563.5 20391.2 20408.6 20441.6 18992.8 19465.9 19720.4 19755.7 19762.3 5236.0 5561.6 5643.3 5732.5 5767.2 5594.0 5647.3 5649.1 5669.2 5669.4 5578.0 5608.5 5618.6 5858.6 5858.9 5873.2 5875.4 5879.3 5890.6 5914.3 5432.9 5632.1 5638.6 5639.9 5640.2 13094.0 13220.7 13226.0 13377.8 13492.2 2228.2 2348.1 2357.7 2644.3 2759.3 9294.0 9299.7 9375.5 9404.9 9795.7 1856.3 1864.5 1876.6 1919.6 1946.8 60923.0 83925.0 84192.6 84644.6 86752.6 9656.4 9983.2 10091.4 10112.1 10153.3 13181.2 13720.7 14392.6 14433.5 14453.8 7447.7 7514.4 7520.1 7646.9 7755.8 7199.9 7240.1 7262.6 7415.0 7454.7 4397.2 4466.5 4554.5 4561.0 4576.3 1845.7 1879.9 1909.5 1928.3 1932.9 992.6 1007.7 1043.8 1078.7 1079.7 18869.2 19288.8 19293.1 19391.0 19460.5 937.6 991.5 992.7 1000.4 1011.5 10124.5 10617.9 10655.7 10724.9 10778.7 510257.0 516234.0 521481.0 530380.0 549055.0 93668.0 93827.5 98390.5 99187.0 102901.5 1022.7 1029.8 1033.4 1043.1 1057.6 16798.2 16914.2 16923.4 17112.3 17185.6 41174.0 42431.8 42776.8 43330.8 45286.3 58054.4 59649.7 64621.0 66318.0 67187.7 7819.2 8130.0 8222.9 8286.6 8434.2 9367.3 12493.9 12772.9 12910.9 12949.0 11172.8 11283.8 11317.3 11898.7 12035.5 11139.9 11245.3 11287.4 11344.7 11402.9 10548.1 11663.2 11715.7 11848.7 11961.3 7039.3 7644.4 7668.0 7812.1 8509.1 10632.0 11264.3 11466.3 11618.1 11804.0 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | [#3] mootools.html Cycles:180 Average:5889.10 Median:2463.76 stddev:3432.71 (139.3%) stddev-sans-first:3425.29 15:45:07 INFO - PROCESS | 1613 | Values: 1336.4 1468.5 1490.9 1637.6 1778.6 8498.4 8886.2 8894.2 9131.8 9144.9 9021.1 9204.4 9204.7 9206.5 9210.0 2033.1 2299.6 2338.2 2373.8 2378.1 2383.5 2386.6 2388.0 2390.2 2391.3 2300.3 2331.1 2333.0 2333.0 2333.3 1977.0 2204.8 2276.3 2303.2 2306.1 2289.6 2312.6 2337.1 2342.6 2344.4 8804.6 8949.5 8949.8 8984.0 9082.3 5099.6 5233.6 5254.7 5270.3 5270.9 3194.6 3351.5 3387.7 3407.2 3420.1 1036.1 1077.2 1108.8 1113.6 1116.2 12305.6 12441.2 12495.1 12501.0 12599.3 4459.5 4463.8 4643.5 4647.0 4705.3 10561.8 10580.3 10591.9 10624.7 10668.3 5155.5 5215.1 5219.6 5226.5 5392.1 4182.1 4717.6 4854.8 4893.9 4968.6 3894.4 4042.5 4102.3 4107.3 4157.0 1135.7 1232.0 1232.1 1241.9 1244.9 849.7 851.0 905.0 909.7 942.7 5775.9 5975.3 6016.2 6078.5 6087.8 463.7 640.6 1023.5 1034.5 1040.3 3844.6 3946.1 4000.1 4003.1 4088.3 10978.9 10981.2 11131.1 11140.3 11153.6 10805.2 10956.2 10991.0 11020.3 11107.2 8471.4 8610.4 8615.2 8618.8 8628.8 5783.7 5784.4 5823.4 5836.7 5887.0 10391.9 10591.4 10654.9 10656.6 10676.4 10622.8 10731.9 10974.6 11042.6 11046.5 6893.3 7537.4 7726.5 7779.1 7894.5 9099.2 9120.8 9163.7 9184.8 9188.0 7598.6 7641.6 7728.0 7755.5 8007.2 7627.2 7666.6 7772.0 7788.2 7875.1 7251.8 7264.3 7293.1 7298.3 7315.9 4855.0 5047.7 5074.5 5154.7 5175.2 5029.7 5099.7 5139.9 5173.2 5207.7 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | [#4] prototype.html Cycles:180 Average:4633.51 Median:5340.97 stddev:1365.74 (25.6%) stddev-sans-first:1330.57 15:45:07 INFO - PROCESS | 1613 | Values: 316.1 317.9 318.6 322.9 324.3 5271.6 5289.6 5296.4 5297.6 5301.0 5235.2 5236.9 5263.4 5266.4 5267.3 3948.6 4001.7 4049.3 4051.6 4066.4 3953.5 4133.5 4136.7 4139.2 4146.9 4052.3 4082.8 4087.4 4089.9 4102.4 3932.5 4025.0 4098.7 4101.1 4106.8 3528.2 3560.6 3565.9 3620.0 3634.5 5090.5 5094.6 5108.7 5118.9 5132.7 4546.4 4664.7 4667.6 4673.2 4676.5 5421.3 5428.2 5431.4 5438.6 5441.7 3960.4 3960.7 3963.3 3963.8 3964.2 5223.8 5552.6 5639.6 5651.3 5656.4 3965.0 4004.7 4017.8 4023.8 4034.8 4017.9 4019.3 4030.5 4065.6 4073.3 4427.3 4641.9 4643.4 4654.8 4658.7 5501.5 5502.9 5520.4 5523.8 5530.9 1392.6 1409.5 1410.6 1411.6 1413.3 898.3 914.7 926.7 953.6 962.7 5354.2 5379.8 5388.8 5392.7 5404.3 5592.6 5595.3 5608.1 5691.9 5723.8 5114.4 5245.7 5326.4 5327.8 5367.9 3003.9 3005.1 3020.2 3030.2 3031.7 5502.7 5822.7 5828.5 5837.9 5848.9 5681.9 5737.8 5740.4 5749.5 5798.3 5745.6 5754.9 5772.6 5787.6 5796.2 5419.6 5576.3 5608.3 5622.7 5639.0 5476.8 5502.4 5507.9 5532.3 5533.2 5508.8 5601.8 5852.1 5862.5 5876.5 4007.7 4007.9 4016.5 4022.5 4027.9 5372.6 5627.6 5737.5 5761.5 5770.0 5569.7 5578.7 5615.4 5635.3 5644.4 5585.3 5602.0 5604.3 5654.5 5667.8 5247.3 5503.1 5515.0 5516.2 5529.1 5594.2 5595.6 5603.7 5607.3 5616.9 5484.3 5492.5 5499.7 5509.3 5513.2 15:45:07 INFO - PROCESS | 1613 | 15:45:07 INFO - PROCESS | 1613 | [#5] yui.html Cycles:180 Average:21600.99 Median:4320.98 stddev:31521.45 (729.5%) stddev-sans-first:31570.97 15:45:07 INFO - PROCESS | 1613 | Values: 744.7 766.3 768.7 839.8 863.6 15803.8 17150.3 17183.8 17208.4 17273.4 15423.0 15726.5 15736.4 15760.2 15804.2 666.4 789.6 791.3 795.4 825.3 822.5 824.4 828.9 829.6 829.9 710.8 883.0 884.5 885.1 885.5 840.9 863.4 865.1 867.0 868.4 642.1 642.6 642.8 642.8 643.1 14762.9 14782.3 14792.7 14926.8 15010.7 13717.0 13769.0 13822.4 13829.8 14156.9 12400.3 12465.8 12578.5 13001.6 13069.3 4111.3 4163.3 4244.6 4284.1 4374.6 75227.0 75379.2 76212.6 76348.0 76369.6 11117.6 11252.4 11262.9 11270.9 11500.0 20632.8 20648.7 20714.0 20736.4 20753.9 7043.1 7115.8 7361.5 7516.5 7751.7 4357.9 4402.8 4479.7 4481.6 4535.8 3313.8 3432.3 3434.7 3440.8 3445.3 610.0 657.9 663.3 685.0 719.4 660.5 725.9 730.0 738.7 762.2 9204.4 9433.9 9965.5 10025.9 10231.4 629.2 650.9 651.8 656.7 728.3 4941.5 5095.2 5102.4 5106.2 5106.8 77949.6 91312.0 134719.0 138635.0 140061.0 68593.3 69913.7 72561.0 112432.9 114559.0 98326.0 100410.5 100829.5 103001.5 103069.5 9212.2 9708.9 9860.8 9955.2 10023.4 58381.4 58653.7 59299.7 59446.4 60535.7 67100.0 85417.0 86964.0 89988.0 90470.0 13040.3 13803.0 13893.7 14251.7 14614.8 14403.7 15258.5 16044.2 16186.3 16931.3 13637.5 13730.1 13755.1 13890.7 13976.0 14743.8 14924.9 15187.2 15328.2 15335.8 13986.0 14062.8 14380.1 14613.2 14849.3 11721.1 12094.7 12229.3 12282.9 12297.0 11014.8 11063.6 11225.8 11338.9 11582.7 15:45:07 INFO - PROCESS | 1613 | -------- Summary: end -------- 15:45:07 INFO - PROCESS | 1613 | 15:45:08 INFO - TEST-INFO | 1613: exit 0 15:45:08 INFO - TEST-OK | dromaeo_css | took 1119749ms 15:45:08 INFO - TEST-START | kraken 15:45:08 INFO - operating with platform_type : linux_ 15:45:08 INFO - Initialising browser for kraken test... 15:45:08 INFO - TEST-INFO | started process 1801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP9sDxE/profile http://localhost:58932/getInfo.html) 15:45:14 INFO - PROCESS | 1801 | __metrics Screen width/height:1600/1200 15:45:14 INFO - PROCESS | 1801 | colorDepth:24 15:45:14 INFO - PROCESS | 1801 | Browser inner width/height: 1024/697 15:45:14 INFO - PROCESS | 1801 | __metrics 15:45:16 INFO - PROCESS | 1801 | 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" 15:45:16 INFO - PROCESS | 1801 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:45:17 INFO - PROCESS | 1801 | console.error: PushService: 15:45:17 INFO - PROCESS | 1801 | stateChangeProcessEnqueue: Error transitioning state 15:45:17 INFO - PROCESS | 1801 | UnknownError 15:45:17 INFO - TEST-INFO | 1801: exit 0 15:45:17 INFO - Browser initialized. 15:45:17 INFO - Running cycle 1/1 for kraken test... 15:45:17 INFO - TEST-INFO | started process 1886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP9sDxE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:29 INFO - PROCESS | 1886 | RSS: Main: 157818880 15:45:29 INFO - PROCESS | 1886 | 15:45:31 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58932/tests/kraken/driver.html?testName=audio-beat-detection) 15:45:32 INFO - PROCESS | 1886 | RSS: Main: 216879104 15:45:32 INFO - PROCESS | 1886 | 15:45:35 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58932/tests/kraken/driver.html?testName=audio-dft) 15:45:35 INFO - PROCESS | 1886 | RSS: Main: 205148160 15:45:35 INFO - PROCESS | 1886 | 15:45:39 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58932/tests/kraken/driver.html?testName=audio-fft) 15:45:39 INFO - PROCESS | 1886 | RSS: Main: 269672448 15:45:39 INFO - PROCESS | 1886 | 15:45:41 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58932/tests/kraken/driver.html?testName=audio-oscillator) 15:45:42 INFO - PROCESS | 1886 | RSS: Main: 181100544 15:45:42 INFO - PROCESS | 1886 | 15:45:44 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58932/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:45:45 INFO - PROCESS | 1886 | RSS: Main: 185651200 15:45:45 INFO - PROCESS | 1886 | 15:45:48 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58932/tests/kraken/driver.html?testName=imaging-darkroom) 15:45:48 INFO - PROCESS | 1886 | RSS: Main: 219525120 15:45:48 INFO - PROCESS | 1886 | 15:45:52 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58932/tests/kraken/driver.html?testName=imaging-desaturate) 15:45:52 INFO - PROCESS | 1886 | RSS: Main: 221638656 15:45:52 INFO - PROCESS | 1886 | 15:45:55 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58932/tests/kraken/driver.html?testName=json-parse-financial) 15:45:56 INFO - PROCESS | 1886 | RSS: Main: 221831168 15:45:56 INFO - PROCESS | 1886 | 15:45:57 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58932/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:45:58 INFO - PROCESS | 1886 | RSS: Main: 210243584 15:45:58 INFO - PROCESS | 1886 | 15:46:00 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:46:00 INFO - PROCESS | 1886 | RSS: Main: 213602304 15:46:00 INFO - PROCESS | 1886 | 15:46:02 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:46:03 INFO - PROCESS | 1886 | RSS: Main: 211361792 15:46:03 INFO - PROCESS | 1886 | 15:46:05 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:46:06 INFO - PROCESS | 1886 | RSS: Main: 225325056 15:46:06 INFO - PROCESS | 1886 | 15:46:09 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:46:09 INFO - PROCESS | 1886 | RSS: Main: 224518144 15:46:09 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:58932/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58932/tests/kraken/driver.html?testName=ai-astar) 15:46:11 INFO - PROCESS | 1886 | RSS: Main: 267403264 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | __start_tp_report 15:46:11 INFO - PROCESS | 1886 | _x_x_mozilla_page_load 15:46:11 INFO - PROCESS | 1886 | _x_x_mozilla_page_load_details 15:46:11 INFO - PROCESS | 1886 | |i|pagename|runs| 15:46:11 INFO - PROCESS | 1886 | |0;ai-astar;104;113;109;111;114;110;110;110;112;111 15:46:11 INFO - PROCESS | 1886 | |1;audio-beat-detection;152;116;124;176;116;116;153;116;169;122 15:46:11 INFO - PROCESS | 1886 | |2;audio-dft;160;160;162;161;161;161;161;162;162;161 15:46:11 INFO - PROCESS | 1886 | |3;audio-fft;75;74;75;113;75;77;75;75;99;75 15:46:11 INFO - PROCESS | 1886 | |4;audio-oscillator;83;84;115;84;84;84;120;83;85;119 15:46:11 INFO - PROCESS | 1886 | |5;imaging-gaussian-blur;117;117;117;117;117;116;118;116;117;116 15:46:11 INFO - PROCESS | 1886 | |6;imaging-darkroom;146;146;145;145;145;145;147;146;145;146 15:46:11 INFO - PROCESS | 1886 | |7;imaging-desaturate;95;94;95;94;96;95;96;96;95;94 15:46:11 INFO - PROCESS | 1886 | |8;json-parse-financial;51;50;50;51;52;51;55;52;50;50 15:46:11 INFO - PROCESS | 1886 | |9;json-stringify-tinderbox;64;63;63;64;63;63;63;63;63;64 15:46:11 INFO - PROCESS | 1886 | |10;stanford-crypto-aes;76;75;77;81;78;74;78;75;74;85 15:46:11 INFO - PROCESS | 1886 | |11;stanford-crypto-ccm;129;120;119;128;118;117;119;125;163;122 15:46:11 INFO - PROCESS | 1886 | |12;stanford-crypto-pbkdf2;160;161;157;157;157;162;161;159;159;161 15:46:11 INFO - PROCESS | 1886 | |13;stanford-crypto-sha256-iterative;63;63;63;66;61;62;62;62;62;61 15:46:11 INFO - PROCESS | 1886 | __end_tp_report 15:46:11 INFO - PROCESS | 1886 | __start_cc_report 15:46:11 INFO - PROCESS | 1886 | _x_x_mozilla_cycle_collect,1696 15:46:11 INFO - PROCESS | 1886 | __end_cc_report 15:46:11 INFO - PROCESS | 1886 | __startTimestamp1468881971636__endTimestamp 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | ------- Summary: start ------- 15:46:11 INFO - PROCESS | 1886 | Number of tests: 14 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.72 (2.4%) stddev-sans-first:1.62 15:46:11 INFO - PROCESS | 1886 | Values: 104.0 113.0 109.0 111.0 114.0 110.0 110.0 110.0 112.0 111.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:138.00 stddev:23.98 (17.4%) stddev-sans-first:24.72 15:46:11 INFO - PROCESS | 1886 | Values: 152.0 116.0 124.0 176.0 116.0 116.0 153.0 116.0 169.0 122.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 15:46:11 INFO - PROCESS | 1886 | Values: 160.0 160.0 162.0 161.0 161.0 161.0 161.0 162.0 162.0 161.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.00 stddev:13.45 (17.9%) stddev-sans-first:14.07 15:46:11 INFO - PROCESS | 1886 | Values: 75.0 74.0 75.0 113.0 75.0 77.0 75.0 75.0 99.0 75.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:84.00 stddev:16.55 (19.7%) stddev-sans-first:17.06 15:46:11 INFO - PROCESS | 1886 | Values: 83.0 84.0 115.0 84.0 84.0 84.0 120.0 83.0 85.0 119.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 15:46:11 INFO - PROCESS | 1886 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 118.0 116.0 117.0 116.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 15:46:11 INFO - PROCESS | 1886 | Values: 146.0 146.0 145.0 145.0 145.0 145.0 147.0 146.0 145.0 146.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 15:46:11 INFO - PROCESS | 1886 | Values: 95.0 94.0 95.0 94.0 96.0 95.0 96.0 96.0 95.0 94.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:1.55 (3.0%) stddev-sans-first:1.64 15:46:11 INFO - PROCESS | 1886 | Values: 51.0 50.0 50.0 51.0 52.0 51.0 55.0 52.0 50.0 50.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 15:46:11 INFO - PROCESS | 1886 | Values: 64.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 64.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:77.50 stddev:3.47 (4.5%) stddev-sans-first:3.64 15:46:11 INFO - PROCESS | 1886 | Values: 76.0 75.0 77.0 81.0 78.0 74.0 78.0 75.0 74.0 85.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:123.50 stddev:13.65 (11.1%) stddev-sans-first:14.44 15:46:11 INFO - PROCESS | 1886 | Values: 129.0 120.0 119.0 128.0 118.0 117.0 119.0 125.0 163.0 122.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.40 Median:160.50 stddev:1.90 (1.2%) stddev-sans-first:2.00 15:46:11 INFO - PROCESS | 1886 | Values: 160.0 161.0 157.0 157.0 157.0 162.0 161.0 159.0 159.0 161.0 15:46:11 INFO - PROCESS | 1886 | 15:46:11 INFO - PROCESS | 1886 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:1.43 (2.3%) stddev-sans-first:1.51 15:46:11 INFO - PROCESS | 1886 | Values: 63.0 63.0 63.0 66.0 61.0 62.0 62.0 62.0 62.0 61.0 15:46:11 INFO - PROCESS | 1886 | -------- Summary: end -------- 15:46:11 INFO - PROCESS | 1886 | 15:46:12 INFO - TEST-INFO | 1886: exit 0 15:46:13 INFO - TEST-OK | kraken | took 64860ms 15:46:13 INFO - SUITE-END | took 1184s 15:46:13 INFO - Completed test suite (00:19:44) 15:46:13 INFO - javascript benchmark 15:46:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3042.0873737878646, 3044.2693753499157, 3050.7254347108765, 3090.474619314716, 3093.149383919423, 5338.839834804952, 5386.838394848151, 5429.375621803484, 5450.236966824643, 5494.670319780811, 5404.540614047801, 5431.0, 5451.3458384993855, 5465.453454654533, 5514.503694667479, 4867.513248675131, 4930.975345123269, 4964.081644895706, 4964.87587810304, 5126.307948426952, 5025.422076461217, 5064.417591976933, 5077.543021128097, 5090.770915308802, 5093.617978651616, 4850.8059677612855, 4887.0714564232685, 4893.926591101136, 4921.7785199666105, 5045.621578381636, 4821.108095002436, 4828.855134345975, 4833.250846118857, 4901.754912254409, 4950.653454258203, 4876.073546026244, 4876.439209490918, 4895.241237608141, 4898.975505122487, 4909.803607855682, 5370.812021579265, 5412.431694672042, 5476.479734425223, 5519.586031047647, 5535.059039963176, 5867.383924687889, 5889.528837692974, 5900.822975310704, 5916.497097746654, 5919.052951527778, 5747.741351639186, 5930.733117009745, 5935.4361335673475, 5945.078512830518, 5947.672877991708, 5412.621116521845, 5469.972650136724, 5481.561475081984, 5565.053940830109, 5571.804986825442, 6027.035674292092, 6041.909371359433, 6053.576249662569, 6067.0, 6068.9696551517845, 5570.693611851387, 5594.972025139929, 5843.2403787507355, 5851.239338885917, 5854.912176317359, 5688.232088667977, 5788.0, 5792.88414231713, 5843.0, 5843.094320380263, 5682.374938756733, 5735.684537350486, 5739.053056245756, 5740.655560666373, 5769.048107062287, 5676.489115979582, 5693.430656934273, 5722.055860783007, 5752.6836024019085, 5756.280464941882, 4374.256376416017, 4383.60547550722, 4387.0, 4421.80101895409, 4431.955680443219, 3906.5898080701118, 3916.7649941003633, 3921.8627348042683, 3951.565327813938, 3953.1105501261986, 5371.033214021436, 5403.567714582824, 5438.129899216106, 5469.2616496772425, 5511.751971161308, 5871.500922421642, 5891.3224979127945, 5903.881922361528, 5904.881902361929, 5936.079907614327, 5559.305086864142, 5566.777328906879, 5573.888522229533, 5579.302587176603, 5579.4420557944695, 4739.549742774431, 4829.203181475017, 4832.830850920201, 4835.709857408497, 4909.558139767438, 5971.701414929236, 6009.308929473079, 6011.368806275365, 6014.1580178775, 6032.577719559588, 5835.4748072673665, 5858.648481091148, 5863.0, 5885.47030767233, 5885.793997210077, 5877.617954832922, 5925.466707996301, 5962.5528085394635, 5974.820755377345, 6038.63768173911, 5726.74229659658, 5773.191753154478, 5776.046952252831, 5796.1305804129715, 5806.187133801187, 5620.74706638194, 5853.2976042875125, 5854.356020837619, 5880.676562789087, 5890.263717035371, 5647.209390685225, 5703.315602127605, 5704.914426283526, 5710.971445142748, 5712.08606622955, 5698.287714035746, 5701.572382071444, 5704.543636509153, 5705.714714264187, 5706.9714651426475, 5512.6692398456225, 5533.972330138324, 5642.492175704207, 5652.63257888236, 5660.4905558498285, 5675.035244008446, 5701.971490142523, 5719.456651618089, 5776.480116789509, 5778.162166485752, 5539.667619942817, 5584.916226256691, 5587.804426845041, 5589.105743081088, 5592.105263157875], "value": 5356.646885263549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.7784519727701, 603.9305479869815, 614.1616693213759, 620.1411045701705, 628.867937733076, 33403.0, 36143.0, 36241.63758362416, 36271.637283627155, 36304.63695363049, 36419.81790091046, 36685.44971825425, 44690.55309446905, 45865.770671146594, 46245.768771156094, 9078.636854525828, 9191.95404022979, 9192.816143677122, 9212.078792120801, 9224.077592240756, 8803.20771130598, 8887.377883548153, 8894.199522043047, 8895.510746908918, 8899.421537600068, 8945.105489451036, 8965.506897120657, 8976.775580610472, 8978.371513994023, 8979.730608081736, 8893.288536917064, 8910.821783564292, 8943.329250306253, 8946.015938246788, 8946.910530894673, 9104.453732776055, 9165.58754856033, 9171.495567743772, 9172.908270917338, 9173.862392064124, 43629.12741745148, 43892.341614875804, 43994.12011759779, 44029.0, 44056.559434405564, 3532.452469867175, 3646.161382881952, 4110.753354798721, 4124.319487284594, 4149.605787450188, 33544.6645533544, 34400.0, 34765.0, 34792.47811282833, 34819.651803481895, 4195.853145139906, 4249.553796851317, 4338.392625032498, 4376.846810361622, 4467.932981005288, 358595.0, 358790.2060489707, 358932.0, 359189.0, 359521.0, 60360.0, 60892.0, 61256.38743612596, 61269.38730612726, 61382.69308653428, 63492.682536587025, 63705.68147159235, 63751.68124159442, 63850.68074659597, 63895.680521597096, 15596.688066238612, 15784.289706963214, 15794.9210253948, 15795.526134215992, 15797.921010395105, 12073.517059317528, 12104.394780261127, 12123.0, 12124.454399552042, 12131.090168237417, 17243.224054917308, 18107.18517666708, 18187.0, 18220.27118915255, 18273.26906923708, 5917.171595976567, 6090.908636370458, 6091.086337049478, 6171.13604095427, 6177.598456100339, 925.3383830561047, 991.4695637833725, 1035.2856528994969, 1058.2010582010532, 1081.7187531241934, 33036.0, 33375.83312083472, 33443.0, 33657.663423365455, 34335.313293734485, 961.7451375385529, 1101.372217835826, 1108.6784832398675, 1189.0665827325508, 1197.4671271284194, 16661.583460413585, 17444.302227910743, 17944.91027544854, 18031.668235633966, 18866.81133188678, 525570.0, 533011.0, 535460.0, 536555.0, 537647.0, 59743.40256597378, 60187.0, 60237.0, 60278.69860650669, 60300.69849650723, 1251.787196176652, 1346.7037251804588, 1392.7980442835935, 1419.283261952728, 1420.6803469219342, 33179.33641327208, 34480.13799654928, 34634.48048279227, 34679.479807802396, 34682.0, 51614.483855160965, 51674.22488662748, 52262.21606675979, 52384.214236787244, 52413.47586524086, 324705.0, 324974.0, 325578.0, 326580.0, 326966.0, 25007.624885627094, 28211.57682634721, 28586.42827143487, 28634.14097577076, 30193.547096793125, 48776.75611621919, 49426.25860612022, 49494.0, 50618.0, 50629.49370506248, 40973.38539921844, 41490.37764433596, 41947.0, 42796.57203428051, 43351.0, 41899.37150942799, 42128.0, 42502.78748606237, 43002.1399572013, 43096.13807723891, 41788.3731744018, 42464.0, 42968.0, 43170.784146079066, 43187.0, 35641.64358356383, 37318.25363492769, 37648.43527347037, 38070.61929380671, 38493.615063849, 28426.43147137087, 28769.85615071911, 28796.42407151887, 28813.71186288194, 28935.421291574472], "value": 20099.15845414762, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.8398430666966, 382.0515371604815, 383.0385731813148, 383.5589072566549, 401.76697515441026, 19444.51388715281, 19563.5109122272, 20391.184352625896, 20408.59182816343, 20441.591168176645, 18992.810071899275, 19465.902670486663, 19720.40838774839, 19755.703664445045, 19762.30831920883, 5236.0, 5561.555075593963, 5643.266375371196, 5732.48407643312, 5767.163761254616, 5594.049011668027, 5647.29408823897, 5649.12438572022, 5669.206311116447, 5669.404712505189, 5577.972110139443, 5608.523275521586, 5618.5786066045, 5858.5606079543995, 5858.853528661796, 5873.177755114288, 5875.38308477608, 5879.265091863517, 5890.617109887865, 5914.2902851657645, 5432.945670543283, 5632.098864181711, 5638.633488823213, 5639.8590035249035, 5640.238567793377, 13094.0, 13220.735585288337, 13226.0, 13377.799333010013, 13492.190468571916, 2228.186711850163, 2348.0842471436154, 2357.71707395112, 2644.2728249731476, 2759.2550011496983, 9294.024127466586, 9299.72100836976, 9375.484348360837, 9404.90595094047, 9795.706128816148, 1856.2760523395887, 1864.4872660018602, 1876.5777700017522, 1919.5680971781378, 1946.8150525700037, 60923.0, 83925.0, 84192.57903710504, 84644.57677711573, 86752.56623716842, 9656.37233579815, 9983.151432128205, 10091.44497052669, 10112.089911907817, 10153.340032897839, 13181.209127452383, 13720.725585488433, 14392.56822295312, 14433.494827680981, 14453.78319325211, 7447.664855081534, 7514.4364172687265, 7520.059992500937, 7646.923530764621, 7755.806104847311, 7199.856002879913, 7240.094988126484, 7262.600556969312, 7414.962925185339, 7454.664540095709, 4397.1865204937185, 4466.486354069259, 4554.4990051094355, 4560.977195114003, 4576.313552967082, 1845.6954602490707, 1879.9060046997595, 1909.4653497020856, 1928.3154480159621, 1932.9130189141522, 992.5583115513672, 1007.6573964851985, 1043.7755882485303, 1078.6820972605133, 1079.7354648111107, 18869.245230190638, 19288.80711192898, 19293.131809068625, 19391.0, 19460.51348716265, 937.5734041011324, 991.504247876062, 992.6724181020246, 1000.3747657713928, 1011.4588695048114, 10124.493775311204, 10617.946910265553, 10655.668631309616, 10724.946375268073, 10778.676639700821, 510257.0, 516234.0, 521481.0, 530380.0, 549055.0, 93668.0, 93827.53086234526, 98390.50804746074, 99187.0, 102901.48549257356, 1022.7136401807505, 1029.7837454134715, 1033.379972016784, 1043.1289872956168, 1057.5822550092823, 16798.24407901623, 16914.154292285584, 16923.407680731114, 17112.315507379564, 17185.57036074058, 41174.0, 42431.7878410606, 42776.78611606922, 43330.78334608433, 45286.320705190104, 58054.4194558049, 59649.70175149097, 64621.0, 66318.00522992255, 67187.66406167937, 7819.178986206366, 8129.959350203448, 8222.876656850032, 8286.596886902731, 8434.156584341517, 9367.250619950251, 12493.93753031229, 12772.936135319263, 12910.935445322713, 12949.0, 11172.832407514057, 11283.774324513299, 11317.264377815416, 11898.702532436759, 12035.45840437165, 11139.888601113886, 11245.325280483197, 11287.435628218393, 11344.716382090513, 11402.885971140182, 10548.103411209826, 11663.183577149857, 11715.707107322045, 11848.703782405164, 11961.282323060645, 7039.260877607982, 7644.426667999811, 7667.961660191663, 7812.140664527011, 8509.149085091442, 10632.0, 11264.267822591506, 11466.31202127875, 11618.128640352177, 11804.0], "value": 8955.951905321423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.4453751693047, 1468.537410715625, 1490.8732757715595, 1637.6069743261614, 1778.5731424458133, 8498.362622803292, 8886.20024197822, 8894.155055269755, 9131.817363652723, 9144.90855091449, 9021.097890210976, 9204.447733136, 9204.723858284246, 9206.49364284964, 9210.032946540618, 2033.1359172351765, 2299.632058870581, 2338.1582630253138, 2373.7982271506858, 2378.0963233971065, 2383.4875501767115, 2386.59427897257, 2387.9641805372935, 2390.2112302940077, 2391.3184742348435, 2300.275413244831, 2331.1258278145697, 2332.9883350583223, 2333.0434521846046, 2333.3349995251356, 1976.9703454448195, 2204.7684993075654, 2276.3057267533395, 2303.2053941390195, 2306.1351993002622, 2289.5993201189726, 2312.6415405612156, 2337.111897478947, 2342.566625174331, 2344.402177444758, 8804.603792829337, 8949.507777072258, 8949.776255593662, 8983.955080224623, 9082.273418126533, 5099.617528685327, 5233.633645644806, 5254.65844720092, 5270.341207349082, 5270.947290527122, 3194.6006749156354, 3351.514030465569, 3387.745919056056, 3407.2333724911946, 3420.059483642018, 1036.0623635609786, 1077.154433769495, 1108.839218313348, 1113.6325012745774, 1116.179607988128, 12305.569305074368, 12441.191322564095, 12495.125341226118, 12501.0, 12599.3070381129, 4459.531749166355, 4463.776811159429, 4643.535646435329, 4646.976765116153, 4705.341252224691, 10561.841572376421, 10580.312279701795, 10591.887851775453, 10624.734381640521, 10668.30656007357, 5155.53600175986, 5215.061288967946, 5219.556337711261, 5226.529612334908, 5392.137258038695, 4182.079942412664, 4717.646176536773, 4854.78153483087, 4893.926591101136, 4968.577670897942, 3894.4353068804867, 4042.535108462506, 4102.323116685773, 4107.301758700968, 4156.9792151039055, 1135.7387800805861, 1231.950721971129, 1232.1128787273149, 1241.875812418751, 1244.8506179258547, 849.668629234599, 850.953197574127, 905.0180554098745, 909.7179874239007, 942.707760594218, 5775.8556036099435, 5975.282966044103, 6016.2479690038745, 6078.483328917003, 6087.847803804941, 463.7495752293791, 640.5804198250154, 1023.4831410137981, 1034.4775888176573, 1040.2614143957724, 3844.557875844258, 3946.092398748304, 4000.0599859033105, 4003.1193137510286, 4088.325426304686, 10978.945105274583, 10981.231313807957, 11131.053860421793, 11140.331580105058, 11153.609623663133, 10805.243632945618, 10956.233063685624, 10991.0, 11020.283681560833, 11107.166962477846, 8471.449355791978, 8610.397272191012, 8615.181557752003, 8618.784530386665, 8628.784280392914, 5783.7397317119985, 5784.392638772868, 5823.359430462561, 5836.737346819488, 5887.0, 10391.948040259751, 10591.364518128938, 10654.946725266323, 10656.573737050741, 10676.412797296223, 10622.84065738999, 10731.946340268249, 10974.615888443866, 11042.613508527164, 11046.502907369028, 6893.276205998298, 7537.434692398202, 7726.497777644435, 7779.066512018594, 7894.526328420313, 9099.1810737034, 9120.817583648422, 9163.679271225476, 9184.816303673755, 9188.035256297993, 7598.582077985764, 7641.579713115832, 7727.961360193163, 7755.495892766952, 8007.239312265326, 7627.199144090013, 7666.616669166631, 7772.0, 7788.182240864628, 7875.054993400829, 7251.8187045324285, 7264.309890560388, 7293.088363954505, 7298.306660867209, 7315.926840731525, 4854.975725121493, 5047.722375269395, 5074.467180945948, 5154.664946778447, 5175.249588809676, 5029.673071250357, 5099.745012749422, 5139.948600513947, 5173.249878767633, 5207.71357575337], "value": 4645.368778412638, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.07863079124337, 317.8744395963594, 318.5969748268441, 322.85632893362464, 324.263920899558, 5271.578273738101, 5289.576833853293, 5296.364436267649, 5297.629165958385, 5300.985625462328, 5235.214717792333, 5236.895262094766, 5263.4210236874005, 5266.420693723688, 5267.341582302212, 3948.60513948605, 4001.679865610744, 4049.3116170250983, 4051.6150965658217, 4066.3900414937702, 3953.5058117735284, 4133.503979522461, 4136.710430269883, 4139.234241665286, 4146.942529654933, 4052.29084910141, 4082.7958602069925, 4087.448194493735, 4089.8568550100754, 4102.384642303663, 3932.508436445444, 4024.979875100635, 4098.692598055158, 4101.138760860223, 4106.7946602670045, 3528.2237907660274, 3560.608333083359, 3565.893023209308, 3620.0, 3634.4548317752283, 5090.541851233407, 5094.566961808214, 5108.66793658411, 5118.923216151761, 5132.666376685504, 4546.40896683429, 4664.720116793003, 4667.603253723438, 4673.228917228653, 4676.50896655853, 5421.316448358049, 5428.240046393508, 5431.402545719968, 5438.564914806845, 5441.727913604304, 3960.3663413853646, 3960.683145348365, 3963.346047902093, 3963.801809909493, 3964.207158568298, 5223.764930578133, 5552.639078459928, 5639.577031722596, 5651.265335506358, 5656.4060773618585, 3964.980175099106, 4004.6796256299426, 4017.7902153815285, 4023.8189281482405, 4034.8184331705143, 4017.8593749218635, 4019.2966230909474, 4030.536488303824, 4065.5527891931865, 4073.2668119738137, 4427.335899615084, 4641.930371044437, 4643.44278686553, 4654.790534425959, 4658.718852315573, 5501.504864562208, 5502.862428439321, 5520.447955204448, 5523.7790488380815, 5530.949119667331, 1392.6379141423304, 1409.5137177673687, 1410.619132834129, 1411.6047506698142, 1413.2721648351107, 898.3038145437208, 914.6890057380523, 926.6941909169958, 953.6233187890745, 962.7159987803615, 5354.170103633943, 5379.838604841861, 5388.811391601275, 5392.703401312965, 5404.32445944257, 5592.552595792326, 5595.300587426572, 5608.10270356741, 5691.943080569225, 5723.7996670116345, 5114.386273647112, 5245.737713114406, 5326.44072372403, 5327.760250788724, 5367.946320536823, 3003.8585337571585, 3005.128512731327, 3020.154356780105, 3030.1515575638855, 3031.681673424303, 5502.697351645698, 5822.679752613562, 5828.504577110908, 5837.883242335214, 5848.912266316009, 5681.886362272731, 5737.799177028784, 5740.368559458456, 5749.511291540182, 5798.275215598051, 5745.597808153389, 5754.913676294859, 5772.5959182856795, 5787.623804452782, 5796.246487956623, 5419.593530485308, 5576.275084239023, 5608.298962629671, 5622.7469763861545, 5638.971805141112, 5476.7535460903555, 5502.431806935005, 5507.915567282334, 5532.28080349552, 5533.197686335378, 5508.779648814003, 5601.767611125546, 5852.122181672783, 5862.530997520274, 5876.500497457764, 4007.6793856490826, 4007.899802504961, 4016.5380981187536, 4022.517297924268, 4027.8791636250958, 5372.62391632582, 5627.606067575231, 5737.540996720169, 5761.48146666802, 5770.0, 5569.66582005081, 5578.693171875423, 5615.410381910004, 5635.267415235993, 5644.435556444323, 5585.301837270341, 5601.971990140023, 5604.299462567179, 5654.547636189012, 5667.801626943037, 5247.2684014275965, 5503.083365816124, 5515.007298686275, 5516.227728118147, 5529.0924631002235, 5594.244776955059, 5595.63628364155, 5603.691796951207, 5607.271054762855, 5616.915746263891, 5484.327280179424, 5492.5331346835965, 5499.725013749377, 5509.256250406304, 5513.2005859151], "value": 4195.036327000639, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.6946751831748, 766.2720415605174, 768.7424712721238, 839.8278352937645, 863.6415887406727, 15803.841961580381, 17150.313987440488, 17183.82816171838, 17208.39770608029, 17273.395431159897, 15423.0, 15726.528204153865, 15736.370545178179, 15760.211989400541, 15804.209789510507, 666.3503084492618, 789.6091434739811, 791.2522666080556, 795.3796039089519, 825.3149885594942, 822.4571782623469, 824.4146655874337, 828.904675962263, 829.6391069884589, 829.9419040667156, 710.8080818179088, 883.0154377868662, 884.5356188001318, 885.1237275097671, 885.4598694796169, 840.8528507511161, 863.386995233385, 865.126222515261, 867.0332579174238, 868.3617541107282, 642.1299139665732, 642.6401215319389, 642.7975187815823, 642.8328634555003, 643.1221382812428, 14762.852371476256, 14782.334794934146, 14792.704145917129, 14926.776098358425, 15010.69978600422, 13717.0, 13768.967327450482, 13822.447102115902, 13829.79255311171, 14156.92921535396, 12400.317982510958, 12465.75068498625, 12578.496860125584, 13001.609951701465, 13069.346532673424, 4111.259973204822, 4163.271427500175, 4244.639205667522, 4284.078923031563, 4374.628156606692, 75227.0, 75379.24620753777, 76212.61893690552, 76347.99999999945, 76369.61815190944, 11117.610883619034, 11252.381119038368, 11262.943685281522, 11270.94364528172, 11500.0, 20632.793672063388, 20648.690269645966, 20714.0, 20736.377908662766, 20753.896230518752, 7043.119610048744, 7115.786526404217, 7361.484696071224, 7516.47384683067, 7751.689932402641, 4357.8725676612885, 4402.779861006937, 4479.664025198123, 4481.574250446202, 4535.7732113394195, 3313.7514686398617, 3432.2792213634916, 3434.6737059979264, 3440.82795860206, 3445.3109378124477, 610.0452791381474, 657.8881590129689, 663.3167837127767, 684.9589024658536, 719.4064896460441, 660.5112216959416, 725.8983742276085, 729.9708011679579, 738.7340557399332, 762.1920763990188, 9204.40171388871, 9433.858492122623, 9965.501724913725, 10025.899741002642, 10231.429954632318, 629.1852051593198, 650.902364645307, 651.824007517977, 656.6815094679076, 728.2535401213798, 4941.456439791692, 5095.210242412432, 5102.438731739506, 5106.234064890222, 5106.770195341219, 77949.61025194838, 91312.0, 134719.0, 138635.0, 140061.0, 68593.31406685969, 69913.65043174752, 72561.0, 112432.87567124225, 114559.0, 98326.0, 100410.4979475098, 100829.49585252174, 103001.48499257605, 103069.48465257775, 9212.170904618617, 9708.85436718464, 9860.753481163028, 9955.20358371343, 10023.398596084258, 58381.416185837596, 58653.70673146607, 59299.70350148222, 59446.405535945814, 60535.69732151311, 67100.0, 85417.0, 86964.0, 89988.0, 90470.0, 13040.282784446947, 13803.0, 13893.652658683613, 14251.714965700836, 14614.780778288547, 14403.664255623826, 15258.549578111455, 16044.171803598758, 16186.271617776993, 16931.32274708998, 13637.522686705917, 13730.107543009672, 13755.10591811569, 13890.722185556435, 13975.95652412585, 14743.778843317144, 14924.85075149278, 15187.164705941279, 15328.233588320762, 15335.846641533442, 13986.0, 14062.789058163931, 14380.065295755743, 14613.19627420502, 14849.257537123316, 11721.12091593117, 12094.697632558904, 12229.266244025386, 12282.938585307375, 12297.0, 11014.834777478505, 11063.612773552886, 11225.775484490428, 11338.943305283421, 11582.6525204244], "value": 7743.813161259828, "unit": "score"}], "name": "dromaeo_css", "value": 7252.533343860694, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 109.0, 111.0, 114.0, 110.0, 110.0, 110.0, 112.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 124.0, 176.0, 116.0, 116.0, 153.0, 116.0, 169.0, 122.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 113.0, 75.0, 77.0, 75.0, 75.0, 99.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 84.0, 84.0, 84.0, 120.0, 83.0, 85.0, 119.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 147.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 96.0, 95.0, 96.0, 96.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 51.0, 52.0, 51.0, 55.0, 52.0, 50.0, 50.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 81.0, 78.0, 74.0, 78.0, 75.0, 74.0, 85.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 119.0, 128.0, 118.0, 117.0, 119.0, 125.0, 163.0, 122.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 157.0, 157.0, 157.0, 162.0, 161.0, 159.0, 159.0, 161.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 66.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1480.0, "alertThreshold": 2.0}]} 15:46:13 INFO - Return code: 0 15:46:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:46:13 INFO - # TBPL SUCCESS # 15:46:13 INFO - Running post-action listener: _resource_record_post_action 15:46:13 INFO - ##### 15:46:13 INFO - ##### Finished run-tests step (success) 15:46:13 INFO - ##### 15:46:13 INFO - Running post-run listener: _resource_record_post_run 15:46:13 INFO - Total resource usage - Wall time: 1211s; CPU: 13.0%; Read bytes: 4096; Write bytes: 556253184; Read time: 212; Write time: 3846208 15:46:13 INFO - TinderboxPrint: CPU usage
13.4% 15:46:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 212 15:46:13 INFO - TinderboxPrint: I/O write bytes / time
556,253,184 / 3,846,208 15:46:13 INFO - TinderboxPrint: CPU idle
8,366.8 (86.6%) 15:46:13 INFO - TinderboxPrint: CPU user
1,194.5 (12.4%) 15:46:13 INFO - TinderboxPrint: Swap in / out
0 / 0 15:46:13 INFO - install - Wall time: 27s; CPU: 25.0%; Read bytes: 0; Write bytes: 257294336; Read time: 0; Write time: 2459132 15:46:13 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296198144; Read time: 212; Write time: 1300904 15:46:14 INFO - Running post-run listener: _upload_blobber_files 15:46:14 INFO - Blob upload gear active. 15:46:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:46:14 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 15:46:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:46:14 INFO - (blobuploader) - INFO - Open directory for files ... 15:46:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:46:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:46:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:15 INFO - (blobuploader) - INFO - Done attempting. 15:46:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:46:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:46:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:25 INFO - (blobuploader) - INFO - Done attempting. 15:46:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:46:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:46:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:26 INFO - (blobuploader) - INFO - Done attempting. 15:46:26 INFO - (blobuploader) - INFO - Iteration through files over. 15:46:26 INFO - Return code: 0 15:46:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:46:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:46:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25b0e9bb6a84feb13b064f98aa64df308e7fa3cc73a5cc365ca3d83653dba6f8a050ffe8ecc8899e299c22608a2b79c4bfc7cad619a4923fb6d885a621e22ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4463c5d3297a50b22039efd178ca3bfcc7a4a018e5872de282d85de8efd9ecb25fc7b029520c333f7f35e45a1a1272f4f856c20a4e494ff3ac58d521f1d5431f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77717695c4a456c3eda9ef12bc0dcd731bdd65e9d64017222ed8e50a99595bd65b0333488fed96adc684cc9005374aa95ee2b22caccc76004c8d18f0cf131f88"} 15:46:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:46:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:46:26 INFO - Contents: 15:46:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25b0e9bb6a84feb13b064f98aa64df308e7fa3cc73a5cc365ca3d83653dba6f8a050ffe8ecc8899e299c22608a2b79c4bfc7cad619a4923fb6d885a621e22ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4463c5d3297a50b22039efd178ca3bfcc7a4a018e5872de282d85de8efd9ecb25fc7b029520c333f7f35e45a1a1272f4f856c20a4e494ff3ac58d521f1d5431f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77717695c4a456c3eda9ef12bc0dcd731bdd65e9d64017222ed8e50a99595bd65b0333488fed96adc684cc9005374aa95ee2b22caccc76004c8d18f0cf131f88"} 15:46:26 INFO - Running post-run listener: copy_logs_to_upload_dir 15:46:26 INFO - Copying logs to upload dir... 15:46:26 INFO - mkdir: /builds/slave/test/build/upload/logs 15:46:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.337968 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-18 15:46:24.903854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 15:46:24.907378) ========= 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=33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25b0e9bb6a84feb13b064f98aa64df308e7fa3cc73a5cc365ca3d83653dba6f8a050ffe8ecc8899e299c22608a2b79c4bfc7cad619a4923fb6d885a621e22ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4463c5d3297a50b22039efd178ca3bfcc7a4a018e5872de282d85de8efd9ecb25fc7b029520c333f7f35e45a1a1272f4f856c20a4e494ff3ac58d521f1d5431f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77717695c4a456c3eda9ef12bc0dcd731bdd65e9d64017222ed8e50a99595bd65b0333488fed96adc684cc9005374aa95ee2b22caccc76004c8d18f0cf131f88"} build_url:https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011119 build_url: 'https://queue.taskcluster.net/v1/task/dHtqAl6kQvaD74dyISGAhg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25b0e9bb6a84feb13b064f98aa64df308e7fa3cc73a5cc365ca3d83653dba6f8a050ffe8ecc8899e299c22608a2b79c4bfc7cad619a4923fb6d885a621e22ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4463c5d3297a50b22039efd178ca3bfcc7a4a018e5872de282d85de8efd9ecb25fc7b029520c333f7f35e45a1a1272f4f856c20a4e494ff3ac58d521f1d5431f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77717695c4a456c3eda9ef12bc0dcd731bdd65e9d64017222ed8e50a99595bd65b0333488fed96adc684cc9005374aa95ee2b22caccc76004c8d18f0cf131f88"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 15:46:24.938444) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:46:24.938893) ========= 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=33d5923976266a1c518c4bf7000001ab-1468873530.868406-567284905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 15:46:25.017927) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 15:46:25.018273) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 15:46:25.018596) ========= ========= Total master_lag: 0.20 =========