builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1463109392.82 results: success (0) buildid: 20160512173002 builduid: a0ca6b4540c14774bbe81b70ad62902f revision: bec48bfd05a8c6703aab63181441869a3dcaa4cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:32.815705) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:32.816109) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:32.816381) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:32.867147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:32.867453) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 20:16:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 232M=0s 2016-05-12 20:16:33 (232 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.197839 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.121074) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.121354) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.047024 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.180592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.180909) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bec48bfd05a8c6703aab63181441869a3dcaa4cc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev bec48bfd05a8c6703aab63181441869a3dcaa4cc --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=edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 20:16:33,242 truncating revision to first 12 chars 2016-05-12 20:16:33,242 Setting DEBUG logging. 2016-05-12 20:16:33,242 attempt 1/10 2016-05-12 20:16:33,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bec48bfd05a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 20:16:33,498 unpacking tar archive at: fx-team-bec48bfd05a8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.548451 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.743979) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.744264) ========= script_repo_revision: bec48bfd05a8c6703aab63181441869a3dcaa4cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.744637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.745022) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.759073) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-12 20:16:33.759364) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:16:34 INFO - MultiFileLogger online at 20160512 20:16:34 in /builds/slave/test 20:16:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:16:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:16:34 INFO - {'append_to_log': False, 20:16:34 INFO - 'base_work_dir': '/builds/slave/test', 20:16:34 INFO - 'blob_upload_branch': 'Fx-Team', 20:16:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:16:34 INFO - 'branch': 'Fx-Team', 20:16:34 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:34 INFO - 'config_files': ('talos/linux_config.py',), 20:16:34 INFO - 'default_actions': ('clobber', 20:16:34 INFO - 'read-buildbot-config', 20:16:34 INFO - 'download-and-extract', 20:16:34 INFO - 'populate-webroot', 20:16:34 INFO - 'create-virtualenv', 20:16:34 INFO - 'install', 20:16:34 INFO - 'run-tests'), 20:16:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:34 INFO - 'download_minidump_stackwalk': True, 20:16:34 INFO - 'download_symbols': 'ondemand', 20:16:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:16:34 INFO - 'tooltool.py': '/tools/tooltool.py', 20:16:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:16:34 INFO - '/tools/misc-python/virtualenv.py')}, 20:16:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:34 INFO - 'installer_path': 'installer.exe', 20:16:34 INFO - 'log_level': 'info', 20:16:34 INFO - 'log_name': 'talos', 20:16:34 INFO - 'log_to_console': True, 20:16:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:16:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:16:34 INFO - 'opt_config_files': (), 20:16:34 INFO - 'pip_index': False, 20:16:34 INFO - 'sps_profile': False, 20:16:34 INFO - 'sps_profile_interval': 0, 20:16:34 INFO - 'suite': 'dromaeojs', 20:16:34 INFO - 'system_bits': '32', 20:16:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:34 INFO - 'title': 'talos-linux64-ix-033', 20:16:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:16:34 INFO - 'use_talos_json': True, 20:16:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:16:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:34 INFO - 'work_dir': 'build'} 20:16:34 INFO - ##### 20:16:34 INFO - ##### Running clobber step. 20:16:34 INFO - ##### 20:16:34 INFO - Running pre-action listener: _resource_record_pre_action 20:16:34 INFO - Running main action method: clobber 20:16:34 INFO - rmtree: /builds/slave/test/build 20:16:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:16:36 INFO - Running post-action listener: _resource_record_post_action 20:16:36 INFO - ##### 20:16:36 INFO - ##### Finished clobber step (success) 20:16:36 INFO - ##### 20:16:36 INFO - ##### 20:16:36 INFO - ##### Running read-buildbot-config step. 20:16:36 INFO - ##### 20:16:36 INFO - Running pre-action listener: _resource_record_pre_action 20:16:36 INFO - Running main action method: read_buildbot_config 20:16:36 INFO - Using buildbot properties: 20:16:36 INFO - { 20:16:36 INFO - "project": "", 20:16:36 INFO - "product": "firefox", 20:16:36 INFO - "script_repo_revision": "production", 20:16:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:16:36 INFO - "repository": "", 20:16:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:16:36 INFO - "buildid": "20160512173002", 20:16:36 INFO - "pgo_build": "True", 20:16:36 INFO - "basedir": "/builds/slave/test", 20:16:36 INFO - "buildnumber": 670, 20:16:36 INFO - "slavename": "talos-linux64-ix-033", 20:16:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:16:36 INFO - "platform": "ubuntu64_hw", 20:16:36 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:16:36 INFO - "branch": "fx-team", 20:16:36 INFO - "repo_path": "integration/fx-team", 20:16:36 INFO - "revision": "bec48bfd05a8c6703aab63181441869a3dcaa4cc", 20:16:36 INFO - "stage_platform": "linux64-pgo", 20:16:36 INFO - "builduid": "a0ca6b4540c14774bbe81b70ad62902f", 20:16:36 INFO - "slavebuilddir": "test" 20:16:36 INFO - } 20:16:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:16:36 INFO - Running post-action listener: _resource_record_post_action 20:16:36 INFO - ##### 20:16:36 INFO - ##### Finished read-buildbot-config step (success) 20:16:36 INFO - ##### 20:16:36 INFO - ##### 20:16:36 INFO - ##### Running download-and-extract step. 20:16:36 INFO - ##### 20:16:36 INFO - Running pre-action listener: _resource_record_pre_action 20:16:36 INFO - Running main action method: download_and_extract 20:16:36 INFO - mkdir: /builds/slave/test/build/tests 20:16:36 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')]} 20:16:36 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:16:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:16:46 INFO - Downloaded 1361 bytes. 20:16:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:46 INFO - Using the following test package requirements: 20:16:46 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:16:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:16:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:16:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'jsshell-linux-x86_64.zip'], 20:16:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:16:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:16:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:16:46 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:16:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:16:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:16:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:16:46 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')]} 20:16:46 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:16:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:16:47 INFO - Downloaded 23902869 bytes. 20:16:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:16:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:16:49 INFO - Return code: 0 20:16:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:16:49 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:16:50 INFO - Downloaded 23902869 bytes. 20:16:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:16:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:16:52 INFO - Return code: 0 20:16:52 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')]} 20:16:52 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:16:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:16:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:16:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:16:53 INFO - Downloaded 10840239 bytes. 20:16:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:16:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:16:53 INFO - Return code: 0 20:16:53 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')]} 20:16:53 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:16:55 INFO - Downloaded 58370315 bytes. 20:16:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:55 INFO - mkdir: /builds/slave/test/properties 20:16:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:16:55 INFO - Writing to file /builds/slave/test/properties/build_url 20:16:55 INFO - Contents: 20:16:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:56 INFO - Running post-action listener: _resource_record_post_action 20:16:56 INFO - Running post-action listener: set_extra_try_arguments 20:16:56 INFO - ##### 20:16:56 INFO - ##### Finished download-and-extract step (success) 20:16:56 INFO - ##### 20:16:56 INFO - ##### 20:16:56 INFO - ##### Running populate-webroot step. 20:16:56 INFO - ##### 20:16:56 INFO - Running pre-action listener: _resource_record_pre_action 20:16:56 INFO - Running main action method: populate_webroot 20:16:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:16:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:16:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:16:56 INFO - '--tppagecycles', 20:16:56 INFO - '7'], 20:16:56 INFO - 'tests': ['tsvgm']}}, 20:16:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:16:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:16:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:16:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:16:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['damp', 'tps']}, 20:16:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'tests': ['damp', 'tps']}, 20:16:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['dromaeo_dom']}, 20:16:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:16:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['a11yr', 20:16:56 INFO - 'ts_paint', 20:16:56 INFO - 'tpaint', 20:16:56 INFO - 'sessionrestore', 20:16:56 INFO - 'sessionrestore_no_auto_restore', 20:16:56 INFO - 'tabpaint']}, 20:16:56 INFO - 'other-e10s': {'tests': ['a11yr', 20:16:56 INFO - 'ts_paint', 20:16:56 INFO - 'tpaint', 20:16:56 INFO - 'sessionrestore', 20:16:56 INFO - 'sessionrestore_no_auto_restore', 20:16:56 INFO - 'tabpaint']}, 20:16:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:16:56 INFO - 'ts_paint', 20:16:56 INFO - 'tpaint', 20:16:56 INFO - 'sessionrestore', 20:16:56 INFO - 'sessionrestore_no_auto_restore', 20:16:56 INFO - 'tabpaint']}, 20:16:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:16:56 INFO - 'ts_paint', 20:16:56 INFO - 'tpaint', 20:16:56 INFO - 'sessionrestore', 20:16:56 INFO - 'sessionrestore_no_auto_restore', 20:16:56 INFO - 'tabpaint']}, 20:16:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['a11yr', 20:16:56 INFO - 'ts_paint', 20:16:56 INFO - 'tpaint', 20:16:56 INFO - 'sessionrestore', 20:16:56 INFO - 'sessionrestore_no_auto_restore', 20:16:56 INFO - 'tabpaint']}, 20:16:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['a11yr', 20:16:56 INFO - 'ts_paint', 20:16:56 INFO - 'tpaint', 20:16:56 INFO - 'sessionrestore', 20:16:56 INFO - 'sessionrestore_no_auto_restore', 20:16:56 INFO - 'tabpaint']}, 20:16:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['tsvgx', 20:16:56 INFO - 'tsvgr_opacity', 20:16:56 INFO - 'tart', 20:16:56 INFO - 'tscrollx', 20:16:56 INFO - 'cart']}, 20:16:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:16:56 INFO - 'tsvgr_opacity', 20:16:56 INFO - 'tart', 20:16:56 INFO - 'tscrollx', 20:16:56 INFO - 'cart']}, 20:16:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'talos_options': ['--disable-e10s'], 20:16:56 INFO - 'tests': ['tp5o']}, 20:16:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'tests': ['tp5o']}, 20:16:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'talos_options': ['--disable-e10s', 20:16:56 INFO - '--xperf_path', 20:16:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:56 INFO - 'tests': ['tp5n']}, 20:16:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:56 INFO - 'talos_options': ['--xperf_path', 20:16:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:56 INFO - 'tests': ['tp5n']}}, 20:16:56 INFO - 'talos.zip': {'path': '', 20:16:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:16:56 INFO - Running post-action listener: _resource_record_post_action 20:16:56 INFO - ##### 20:16:56 INFO - ##### Finished populate-webroot step (success) 20:16:56 INFO - ##### 20:16:56 INFO - ##### 20:16:56 INFO - ##### Running create-virtualenv step. 20:16:56 INFO - ##### 20:16:56 INFO - Running pre-action listener: _resource_record_pre_action 20:16:56 INFO - Running main action method: create_virtualenv 20:16:56 INFO - Creating virtualenv /builds/slave/test/build/venv 20:16:56 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 20:16:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:16:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:16:58 INFO - Using real prefix '/usr' 20:16:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:16:59 INFO - Installing distribute.............................................................................................................................................................................................done. 20:17:01 INFO - Installing pip.................done. 20:17:01 INFO - Return code: 0 20:17:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:17:01 INFO - Using env: {'DISPLAY': ':0', 20:17:01 INFO - 'HOME': '/home/cltbld', 20:17:01 INFO - 'LANG': 'en_US.UTF-8', 20:17:01 INFO - 'LANGUAGE': 'en_US:en', 20:17:01 INFO - 'LOGNAME': 'cltbld', 20:17:01 INFO - 'MAIL': '/var/mail/cltbld', 20:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:01 INFO - 'MOZ_NO_REMOTE': '1', 20:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:01 INFO - 'NO_EM_RESTART': '1', 20:17:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:01 INFO - 'PWD': '/builds/slave/test', 20:17:01 INFO - 'SHELL': '/bin/bash', 20:17:01 INFO - 'SHLVL': '1', 20:17:01 INFO - 'TERM': 'linux', 20:17:01 INFO - 'TMOUT': '86400', 20:17:01 INFO - 'USER': 'cltbld', 20:17:01 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:01 INFO - '_': '/tools/buildbot/bin/python'} 20:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:01 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 20:17:01 INFO - Cleaning up... 20:17:01 INFO - Return code: 0 20:17:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17: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', 'psutil>=0.7.1'] in /builds/slave/test/build 20:17: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 psutil>=0.7.1 20:17:01 INFO - Using env: {'DISPLAY': ':0', 20:17:01 INFO - 'HOME': '/home/cltbld', 20:17:01 INFO - 'LANG': 'en_US.UTF-8', 20:17:01 INFO - 'LANGUAGE': 'en_US:en', 20:17:01 INFO - 'LOGNAME': 'cltbld', 20:17:01 INFO - 'MAIL': '/var/mail/cltbld', 20:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:01 INFO - 'MOZ_NO_REMOTE': '1', 20:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:01 INFO - 'NO_EM_RESTART': '1', 20:17:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:01 INFO - 'PWD': '/builds/slave/test', 20:17:01 INFO - 'SHELL': '/bin/bash', 20:17:01 INFO - 'SHLVL': '1', 20:17:01 INFO - 'TERM': 'linux', 20:17:01 INFO - 'TMOUT': '86400', 20:17:01 INFO - 'USER': 'cltbld', 20:17:01 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:01 INFO - '_': '/tools/buildbot/bin/python'} 20:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:02 INFO - Downloading/unpacking psutil>=0.7.1 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:17:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:04 INFO - Installing collected packages: psutil 20:17:04 INFO - Running setup.py install for psutil 20:17:04 INFO - building 'psutil._psutil_linux' extension 20:17:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:17:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:17:05 INFO - building 'psutil._psutil_posix' extension 20:17:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:17:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:17:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:05 INFO - Successfully installed psutil 20:17:05 INFO - Cleaning up... 20:17:05 INFO - Return code: 0 20:17:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:05 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:05 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.0.0'] in /builds/slave/test/build 20:17:05 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.0.0 20:17:06 INFO - Using env: {'DISPLAY': ':0', 20:17:06 INFO - 'HOME': '/home/cltbld', 20:17:06 INFO - 'LANG': 'en_US.UTF-8', 20:17:06 INFO - 'LANGUAGE': 'en_US:en', 20:17:06 INFO - 'LOGNAME': 'cltbld', 20:17:06 INFO - 'MAIL': '/var/mail/cltbld', 20:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:06 INFO - 'MOZ_NO_REMOTE': '1', 20:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:06 INFO - 'NO_EM_RESTART': '1', 20:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:06 INFO - 'PWD': '/builds/slave/test', 20:17:06 INFO - 'SHELL': '/bin/bash', 20:17:06 INFO - 'SHLVL': '1', 20:17:06 INFO - 'TERM': 'linux', 20:17:06 INFO - 'TMOUT': '86400', 20:17:06 INFO - 'USER': 'cltbld', 20:17:06 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:06 INFO - '_': '/tools/buildbot/bin/python'} 20:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:17:08 INFO - Installing collected packages: mozsystemmonitor 20:17:08 INFO - Running setup.py install for mozsystemmonitor 20:17:08 INFO - Successfully installed mozsystemmonitor 20:17:08 INFO - Cleaning up... 20:17:08 INFO - Return code: 0 20:17:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:17:08 INFO - Using env: {'DISPLAY': ':0', 20:17:08 INFO - 'HOME': '/home/cltbld', 20:17:08 INFO - 'LANG': 'en_US.UTF-8', 20:17:08 INFO - 'LANGUAGE': 'en_US:en', 20:17:08 INFO - 'LOGNAME': 'cltbld', 20:17:08 INFO - 'MAIL': '/var/mail/cltbld', 20:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:08 INFO - 'MOZ_NO_REMOTE': '1', 20:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:08 INFO - 'NO_EM_RESTART': '1', 20:17:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:08 INFO - 'PWD': '/builds/slave/test', 20:17:08 INFO - 'SHELL': '/bin/bash', 20:17:08 INFO - 'SHLVL': '1', 20:17:08 INFO - 'TERM': 'linux', 20:17:08 INFO - 'TMOUT': '86400', 20:17:08 INFO - 'USER': 'cltbld', 20:17:08 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:08 INFO - '_': '/tools/buildbot/bin/python'} 20:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:08 INFO - Downloading/unpacking blobuploader==1.2.4 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:10 INFO - Downloading blobuploader-1.2.4.tar.gz 20:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:17:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:17:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:11 INFO - Downloading docopt-0.6.1.tar.gz 20:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:17:11 INFO - Installing collected packages: blobuploader, requests, docopt 20:17:11 INFO - Running setup.py install for blobuploader 20:17:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:17:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:17:11 INFO - Running setup.py install for requests 20:17:11 INFO - Running setup.py install for docopt 20:17:11 INFO - Successfully installed blobuploader requests docopt 20:17:11 INFO - Cleaning up... 20:17:11 INFO - Return code: 0 20:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:11 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')]} 20:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:11 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')]} 20:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:11 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 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:11 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 20:17:11 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 20:17:11 INFO - Using env: {'DISPLAY': ':0', 20:17:11 INFO - 'HOME': '/home/cltbld', 20:17:11 INFO - 'LANG': 'en_US.UTF-8', 20:17:11 INFO - 'LANGUAGE': 'en_US:en', 20:17:11 INFO - 'LOGNAME': 'cltbld', 20:17:11 INFO - 'MAIL': '/var/mail/cltbld', 20:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:11 INFO - 'MOZ_NO_REMOTE': '1', 20:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:11 INFO - 'NO_EM_RESTART': '1', 20:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:11 INFO - 'PWD': '/builds/slave/test', 20:17:11 INFO - 'SHELL': '/bin/bash', 20:17:11 INFO - 'SHLVL': '1', 20:17:11 INFO - 'TERM': 'linux', 20:17:11 INFO - 'TMOUT': '86400', 20:17:11 INFO - 'USER': 'cltbld', 20:17:11 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:11 INFO - '_': '/tools/buildbot/bin/python'} 20:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:11 INFO - Running setup.py (path:/tmp/pip-EoV0SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:12 INFO - Running setup.py (path:/tmp/pip-sR09T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:12 INFO - Running setup.py (path:/tmp/pip-Q1X5iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:12 INFO - Running setup.py (path:/tmp/pip-AVvVf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:12 INFO - Running setup.py (path:/tmp/pip-q5qV1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:12 INFO - Running setup.py (path:/tmp/pip-0q5pnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:12 INFO - Running setup.py (path:/tmp/pip-dlv2rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:12 INFO - Running setup.py (path:/tmp/pip-V1BiSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:12 INFO - Running setup.py (path:/tmp/pip-eVncle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:12 INFO - Running setup.py (path:/tmp/pip-gRK2nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:12 INFO - Running setup.py (path:/tmp/pip-ukSDxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:12 INFO - Running setup.py (path:/tmp/pip-ic03Pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:12 INFO - Running setup.py (path:/tmp/pip-GLK2mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:12 INFO - Running setup.py (path:/tmp/pip-OvUYNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:12 INFO - Running setup.py (path:/tmp/pip-v_Z3rS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:13 INFO - Running setup.py (path:/tmp/pip-9ck5gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:13 INFO - Running setup.py (path:/tmp/pip-rne8S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:17:13 INFO - Running setup.py install for manifestparser 20:17:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozcrash 20:17:13 INFO - Running setup.py install for mozdebug 20:17:13 INFO - Running setup.py install for mozdevice 20:17:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozfile 20:17:13 INFO - Running setup.py install for mozhttpd 20:17:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozinfo 20:17:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozInstall 20:17:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozleak 20:17:14 INFO - Running setup.py install for mozlog 20:17:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Running setup.py install for moznetwork 20:17:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Running setup.py install for mozprocess 20:17:14 INFO - Running setup.py install for mozprofile 20:17:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Running setup.py install for mozrunner 20:17:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Running setup.py install for mozscreenshot 20:17:14 INFO - Running setup.py install for moztest 20:17:14 INFO - Running setup.py install for mozversion 20:17:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:17:14 INFO - Cleaning up... 20:17:14 INFO - Return code: 0 20:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:14 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 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:14 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 20:17:14 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 20:17:14 INFO - Using env: {'DISPLAY': ':0', 20:17:14 INFO - 'HOME': '/home/cltbld', 20:17:14 INFO - 'LANG': 'en_US.UTF-8', 20:17:14 INFO - 'LANGUAGE': 'en_US:en', 20:17:14 INFO - 'LOGNAME': 'cltbld', 20:17:14 INFO - 'MAIL': '/var/mail/cltbld', 20:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:14 INFO - 'MOZ_NO_REMOTE': '1', 20:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:14 INFO - 'NO_EM_RESTART': '1', 20:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:14 INFO - 'PWD': '/builds/slave/test', 20:17:14 INFO - 'SHELL': '/bin/bash', 20:17:14 INFO - 'SHLVL': '1', 20:17:14 INFO - 'TERM': 'linux', 20:17:14 INFO - 'TMOUT': '86400', 20:17:14 INFO - 'USER': 'cltbld', 20:17:14 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:14 INFO - '_': '/tools/buildbot/bin/python'} 20:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:15 INFO - Running setup.py (path:/tmp/pip-iN9Nvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:15 INFO - Running setup.py (path:/tmp/pip-cmIcgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:15 INFO - Running setup.py (path:/tmp/pip-h7ZEzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:15 INFO - Running setup.py (path:/tmp/pip-XPtA6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:15 INFO - Running setup.py (path:/tmp/pip-SlIGQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:15 INFO - Running setup.py (path:/tmp/pip-1yfD2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:15 INFO - Running setup.py (path:/tmp/pip-agIkLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:15 INFO - Running setup.py (path:/tmp/pip-CKl_xK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:15 INFO - Running setup.py (path:/tmp/pip-oWWCMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:15 INFO - Running setup.py (path:/tmp/pip-z2z6kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:15 INFO - Running setup.py (path:/tmp/pip-dYGw8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:15 INFO - Running setup.py (path:/tmp/pip-720J_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:16 INFO - Running setup.py (path:/tmp/pip-bTfzeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:16 INFO - Running setup.py (path:/tmp/pip-T1jJHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:16 INFO - Running setup.py (path:/tmp/pip-kkWScJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:16 INFO - Running setup.py (path:/tmp/pip-HQUUYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:16 INFO - Running setup.py (path:/tmp/pip-oNv_qH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:16 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)) 20:17:16 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)) 20:17:16 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)) 20:17:16 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)) 20:17:16 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)) 20:17:16 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)) 20:17:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17:16 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 20:17:16 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 20:17:16 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 20:17:16 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 20:17:16 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 20:17:16 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 20:17:18 INFO - Downloading blessings-1.6.tar.gz 20:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:17:18 INFO - Installing collected packages: blessings 20:17:18 INFO - Running setup.py install for blessings 20:17:18 INFO - Successfully installed blessings 20:17:18 INFO - Cleaning up... 20:17:18 INFO - Return code: 0 20:17:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:17:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:18 INFO - Reading from file tmpfile_stdout 20:17:18 INFO - Current package versions: 20:17:18 INFO - argparse == 1.2.1 20:17:18 INFO - blessings == 1.6 20:17:18 INFO - blobuploader == 1.2.4 20:17:18 INFO - docopt == 0.6.1 20:17:18 INFO - manifestparser == 1.1 20:17:18 INFO - mozInstall == 1.12 20:17:18 INFO - mozcrash == 0.17 20:17:18 INFO - mozdebug == 0.1 20:17:18 INFO - mozdevice == 0.48 20:17:18 INFO - mozfile == 1.2 20:17:18 INFO - mozhttpd == 0.7 20:17:18 INFO - mozinfo == 0.9 20:17:18 INFO - mozleak == 0.1 20:17:18 INFO - mozlog == 3.2 20:17:18 INFO - moznetwork == 0.27 20:17:18 INFO - mozprocess == 0.22 20:17:18 INFO - mozprofile == 0.28 20:17:18 INFO - mozrunner == 6.11 20:17:18 INFO - mozscreenshot == 0.1 20:17:18 INFO - mozsystemmonitor == 0.0 20:17:18 INFO - moztest == 0.7 20:17:18 INFO - mozversion == 1.4 20:17:18 INFO - psutil == 3.1.1 20:17:18 INFO - requests == 1.2.3 20:17:18 INFO - wsgiref == 0.1.2 20:17:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:18 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')]} 20:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:18 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')]} 20:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:18 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 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:18 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 20:17:18 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 20:17:18 INFO - Using env: {'DISPLAY': ':0', 20:17:18 INFO - 'HOME': '/home/cltbld', 20:17:18 INFO - 'LANG': 'en_US.UTF-8', 20:17:18 INFO - 'LANGUAGE': 'en_US:en', 20:17:18 INFO - 'LOGNAME': 'cltbld', 20:17:18 INFO - 'MAIL': '/var/mail/cltbld', 20:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:18 INFO - 'MOZ_NO_REMOTE': '1', 20:17:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:18 INFO - 'NO_EM_RESTART': '1', 20:17:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:18 INFO - 'PWD': '/builds/slave/test', 20:17:18 INFO - 'SHELL': '/bin/bash', 20:17:18 INFO - 'SHLVL': '1', 20:17:18 INFO - 'TERM': 'linux', 20:17:18 INFO - 'TMOUT': '86400', 20:17:18 INFO - 'USER': 'cltbld', 20:17:18 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:18 INFO - '_': '/tools/buildbot/bin/python'} 20:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 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)) 20:17:18 INFO - Cleaning up... 20:17:18 INFO - Return code: 0 20:17:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:17:18 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')]} 20:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:18 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')]} 20:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:18 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 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:18 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 20:17:18 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 20:17:18 INFO - Using env: {'DISPLAY': ':0', 20:17:18 INFO - 'HOME': '/home/cltbld', 20:17:18 INFO - 'LANG': 'en_US.UTF-8', 20:17:18 INFO - 'LANGUAGE': 'en_US:en', 20:17:18 INFO - 'LOGNAME': 'cltbld', 20:17:18 INFO - 'MAIL': '/var/mail/cltbld', 20:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:18 INFO - 'MOZ_NO_REMOTE': '1', 20:17:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:18 INFO - 'NO_EM_RESTART': '1', 20:17:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:18 INFO - 'PWD': '/builds/slave/test', 20:17:18 INFO - 'SHELL': '/bin/bash', 20:17:18 INFO - 'SHLVL': '1', 20:17:18 INFO - 'TERM': 'linux', 20:17:18 INFO - 'TMOUT': '86400', 20:17:18 INFO - 'USER': 'cltbld', 20:17:18 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:18 INFO - '_': '/tools/buildbot/bin/python'} 20:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:19 INFO - Downloading/unpacking jsonschema 20:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:17:21 INFO - Downloading/unpacking functools32 (from jsonschema) 20:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:21 INFO - Downloading functools32-3.2.3-2.tar.gz 20:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:17:21 INFO - warning: no files found matching '*.txt' 20:17:21 INFO - no previously-included directories found matching 'build' 20:17:21 INFO - no previously-included directories found matching 'dist' 20:17:21 INFO - no previously-included directories found matching '.git*' 20:17:21 INFO - Installing collected packages: jsonschema, functools32 20:17:21 INFO - Running setup.py install for functools32 20:17:21 INFO - warning: no files found matching '*.txt' 20:17:21 INFO - no previously-included directories found matching 'build' 20:17:21 INFO - no previously-included directories found matching 'dist' 20:17:21 INFO - no previously-included directories found matching '.git*' 20:17:21 INFO - Successfully installed jsonschema functools32 20:17:21 INFO - Cleaning up... 20:17:21 INFO - Return code: 0 20:17:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4171d0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fde8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', '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 20:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:17:21 INFO - Using env: {'DISPLAY': ':0', 20:17:21 INFO - 'HOME': '/home/cltbld', 20:17:21 INFO - 'LANG': 'en_US.UTF-8', 20:17:21 INFO - 'LANGUAGE': 'en_US:en', 20:17:21 INFO - 'LOGNAME': 'cltbld', 20:17:21 INFO - 'MAIL': '/var/mail/cltbld', 20:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:21 INFO - 'MOZ_NO_REMOTE': '1', 20:17:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:21 INFO - 'NO_EM_RESTART': '1', 20:17:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:21 INFO - 'PWD': '/builds/slave/test', 20:17:21 INFO - 'SHELL': '/bin/bash', 20:17:21 INFO - 'SHLVL': '1', 20:17:21 INFO - 'TERM': 'linux', 20:17:21 INFO - 'TMOUT': '86400', 20:17:21 INFO - 'USER': 'cltbld', 20:17:21 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:21 INFO - '_': '/tools/buildbot/bin/python'} 20:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:21 INFO - Downloading/unpacking flake8 20:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:17:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:17:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:17:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:24 INFO - no previously-included directories found matching 'docs/_build' 20:17:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:17:24 INFO - Running setup.py install for pep8 20:17:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:24 INFO - no previously-included directories found matching 'docs/_build' 20:17:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:17:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:17:24 INFO - Cleaning up... 20:17:24 INFO - Return code: 0 20:17:24 INFO - Running post-action listener: _resource_record_post_action 20:17:24 INFO - Running post-action listener: _start_resource_monitoring 20:17:24 INFO - Starting resource monitoring. 20:17:24 INFO - ##### 20:17:24 INFO - ##### Finished create-virtualenv step (success) 20:17:24 INFO - ##### 20:17:24 INFO - ##### 20:17:24 INFO - ##### Running install step. 20:17:24 INFO - ##### 20:17:24 INFO - Running pre-action listener: _resource_record_pre_action 20:17:24 INFO - Running main action method: install 20:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:24 INFO - Reading from file tmpfile_stdout 20:17:24 INFO - Detecting whether we're running mozinstall >=1.0... 20:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:17:24 INFO - Reading from file tmpfile_stdout 20:17:24 INFO - Output received: 20:17:24 INFO - Usage: mozinstall [options] installer 20:17:24 INFO - Options: 20:17:24 INFO - -h, --help show this help message and exit 20:17:24 INFO - -d DEST, --destination=DEST 20:17:24 INFO - Directory to install application into. [default: 20:17:24 INFO - "/builds/slave/test"] 20:17:24 INFO - --app=APP Application being installed. [default: firefox] 20:17:24 INFO - mkdir: /builds/slave/test/build/application 20:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:17:41 INFO - Reading from file tmpfile_stdout 20:17:41 INFO - Output received: 20:17:41 INFO - /builds/slave/test/build/application/firefox/firefox 20:17:41 INFO - Running post-action listener: _resource_record_post_action 20:17:41 INFO - ##### 20:17:41 INFO - ##### Finished install step (success) 20:17:41 INFO - ##### 20:17:41 INFO - ##### 20:17:41 INFO - ##### Running run-tests step. 20:17:41 INFO - ##### 20:17:41 INFO - Running pre-action listener: _resource_record_pre_action 20:17:41 INFO - Running main action method: run_tests 20:17:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:17:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:17:41 INFO - Python 2.7.3 20:17:41 INFO - Return code: 0 20:17:41 INFO - grabbing minidump binary from tooltool 20:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:41 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 0x206db20>, '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 0x206ea20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206eeb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:17:41 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 20:17:41 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 20:17:41 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 20:17:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:17:41 INFO - Return code: 0 20:17:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:17:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:17:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:17:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:17:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:17:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:17:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:17:42 INFO - Return code: 0 20:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 20:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 20:17:42 INFO - Using env: {'DISPLAY': ':0', 20:17:42 INFO - 'HOME': '/home/cltbld', 20:17:42 INFO - 'LANG': 'en_US.UTF-8', 20:17:42 INFO - 'LANGUAGE': 'en_US:en', 20:17:42 INFO - 'LOGNAME': 'cltbld', 20:17:42 INFO - 'MAIL': '/var/mail/cltbld', 20:17:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:17:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:42 INFO - 'MOZ_NO_REMOTE': '1', 20:17:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:42 INFO - 'NO_EM_RESTART': '1', 20:17:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:42 INFO - 'PWD': '/builds/slave/test', 20:17:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:17:42 INFO - 'SHELL': '/bin/bash', 20:17:42 INFO - 'SHLVL': '1', 20:17:42 INFO - 'TERM': 'linux', 20:17:42 INFO - 'TMOUT': '86400', 20:17:42 INFO - 'USER': 'cltbld', 20:17:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128', 20:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:42 INFO - '_': '/tools/buildbot/bin/python'} 20:17:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 20:17:43 INFO - mozversion application_buildid: 20160512173002 20:17:43 INFO - mozversion application_changeset: bec48bfd05a8c6703aab63181441869a3dcaa4cc 20:17:43 INFO - mozversion application_display_name: Nightly 20:17:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:17:43 INFO - mozversion application_name: Firefox 20:17:43 INFO - mozversion application_remotingname: firefox 20:17:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:17:43 INFO - mozversion application_vendor: Mozilla 20:17:43 INFO - mozversion application_version: 49.0a1 20:17:43 INFO - mozversion platform_buildid: 20160512173002 20:17:43 INFO - mozversion platform_changeset: bec48bfd05a8c6703aab63181441869a3dcaa4cc 20:17:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:17:43 INFO - mozversion platform_version: 49.0a1 20:17:43 INFO - using testdate: 1463109463 20:17:43 INFO - actual date: 1463109463 20:17:43 INFO - starting webserver on 'localhost:47440' 20:17:43 INFO - SUITE-START | Running 2 tests 20:17:43 INFO - TEST-START | dromaeo_css 20:17:43 INFO - operating with platform_type : linux_ 20:17:43 INFO - Initialising browser for dromaeo_css test... 20:17:43 INFO - TEST-INFO | started process 3292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpynlvV5/profile http://localhost:47440/getInfo.html) 20:17:52 INFO - PROCESS | 3292 | __metrics Screen width/height:1600/1200 20:17:52 INFO - PROCESS | 3292 | colorDepth:24 20:17:52 INFO - PROCESS | 3292 | Browser inner width/height: 1024/697 20:17:52 INFO - PROCESS | 3292 | __metrics 20:17:53 ERROR - PROCESS | 3292 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:17:53 INFO - PROCESS | 3292 | console.error: PushService: 20:17:53 INFO - PROCESS | 3292 | stateChangeProcessEnqueue: Error transitioning state 20:17:53 INFO - PROCESS | 3292 | UnknownError 20:17:54 INFO - TEST-INFO | 3292: exit 0 20:17:54 INFO - Browser initialized. 20:17:54 INFO - Running cycle 1/1 for dromaeo_css test... 20:17:54 INFO - TEST-INFO | started process 3375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpynlvV5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:18:06 INFO - PROCESS | 3375 | RSS: Main: 152203264 20:18:06 INFO - PROCESS | 3375 | 20:18:57 INFO - PROCESS | 3375 | 1463109537635 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] 20:21:05 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47440/tests/dromaeo/cssquery-ext.html) 20:21:07 INFO - PROCESS | 3375 | RSS: Main: 283299840 20:21:07 INFO - PROCESS | 3375 | 20:24:09 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-ext.html (next: http://localhost:47440/tests/dromaeo/cssquery-jquery.html) 20:24:10 INFO - PROCESS | 3375 | RSS: Main: 235581440 20:24:10 INFO - PROCESS | 3375 | 20:27:12 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47440/tests/dromaeo/cssquery-mootools.html) 20:27:12 INFO - PROCESS | 3375 | RSS: Main: 241627136 20:27:12 INFO - PROCESS | 3375 | 20:30:14 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47440/tests/dromaeo/cssquery-prototype.html) 20:30:15 INFO - PROCESS | 3375 | RSS: Main: 232312832 20:30:15 INFO - PROCESS | 3375 | 20:33:18 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47440/tests/dromaeo/cssquery-yui.html) 20:33:18 INFO - PROCESS | 3375 | RSS: Main: 223989760 20:33:18 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-yui.html (next: http://localhost:47440/tests/dromaeo/cssquery-dojo.html) 20:36:21 INFO - PROCESS | 3375 | RSS: Main: 267534336 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | __start_tp_report 20:36:21 INFO - PROCESS | 3375 | _x_x_mozilla_page_load 20:36:21 INFO - PROCESS | 3375 | _x_x_mozilla_page_load_details 20:36:21 INFO - PROCESS | 3375 | |i|pagename|runs| 20:36:21 INFO - PROCESS | 3375 | |0;dojo.html;3142.0887942496693;3155.7475401967845;3173.2542852429683;3206.5831441912546;3235.611726592809;5287.47125287471;5319.335083114611;5374.408815030353;5394.595405344595;5409.6213265071465;5365.248865158881;5390.649607775502;5402;5469.890602187954;5505;4807.375041244633;4830.830920917769;4855.830045948393;4872.537108974641;4876.707397556149;4888.388951381077;4907.681000734959;4909.582685471739;4945.23348880924;4949.331840201581;4755.120302744003;4823.20417131173;4863.416390033183;4886.633502487328;4899.121153808006;4750.572448479619;4781.0676918001;4790.377250957388;4828.396450443694;4866.902661946741;4739.810407583693;4798.992211635561;4801.111794318027;4810;4839.249916262985;5171.22431635254;5221.321228240343;5229.346331708536;5305.1776974569;5318.175682769177;5659.8585035374035;5727.942720572824;5767.855803604902;5786.566007549451;5788.363280039192;5758.337791154029;5761.510271626917;5778.451047150514;5855.970720146414;5857.941420585782;5265.736713164365;5348.5186333230195;5377.731113444352;5381.13901775714;5421.620486565942;5959.821205363846;6005.789797357072;6008.068749343859;6008.128821320884;6018.608790428608;5749.195112684228;5751.4536119068625;5752.913706294409;5758.136279558016;5759.424057594207;5496.862578435491;5639.63342382752;5693.658380497183;5699.857503562444;5780.219670344533;5705.942940570541;5708.80019199326;5713.143028545752;5716.599838011382;5717.342505611825;5696.487316141567;5716.342620598602;5719.685417302005;5739.024365857814;5744.052231381859;4281.593248641374;4336.046069864625;4345.239583072949;4347.260965635849;4371.75955322454;3890.6692931100606;3894.2600905827803;3898.2983063048923;3934.960650393459;3945.447637330776;5473.425290344536;5492.505674489235;5506.889862202814;5523.585731070186;5524.364698059694;5579.302587176603;5605.299337582802;5626.352969408488;5636.126400407861;5653.858653533613;5362.678239305654;5388.38033626138;5399.730013499309;5403.324584426947;5406.8918621627345;4733.71597704132;4761.072639309796;4782.115308667908;4786.736729479912;4812.398450193726;5934.139549765347;5968.850778730566;5994.190784244159;6020.066889632114;6027.0356742921795;5777.364489906191;5782.942170578241;5788.479036886701;5790.594658373873;5793.478586927196;5799.623024503394;5891.882162356643;5999.970000149971;6021;6027.21646185993;5619.494245517923;5633;5669.914951275651;5670.574706896917;5709.8287051388525;5743.3395159557185;5750.856228594152;5764.42355764437;5765.250517432707;5769.9711501442225;5414.458554144554;5592.133219351007;5619.8876022480135;5624.268845050281;5626.156076588544;5657.886842263214;5662.85842853932;5663.3770285267765;5674.319081710056;5721.914171287517;5511.5039646432015;5537.972310138584;5561.721913904369;5578.776848925998;5582.609217354909;5481.5340696041285;5512.145617429305;5601.187827765033;5667.744951477111;5684.8294551163535;5427.05026620332;5505.532029777513;5521.1442226454965;5523;5523.972380138073 20:36:21 INFO - PROCESS | 3375 | |1;ext.html;596.6241268001162;635.7965451055662;648.5265755998121;657.8355411147213;662.2152748992447;43853;45585.316220256726;45702.314465282965;45717.085658286815;48686;46323.30515042277;46683;46698.76650616751;46754;46799.76600116995;8072.55600941948;8940.64237430502;9081;9092.63629454821;10631.308964917294;10367.377957322547;10751.51618177184;10754.462276886163;10765.407902565395;10774.084202842767;10815.945920270387;10860.294080884756;10869.293495922779;10872.945635271813;10877.401742904138;8425.83148337033;10563.947180264126;10758.569657213702;10774.5690172393;10839.18706097038;10282.177425805918;10726.731831704192;10731.141508679366;10737.40944248066;10788.136949044136;41846.372304415156;42321.788391057846;42325.36511952323;42337.364939525934;42347.36478952818;3613.819309034564;3711.480392745018;3828.464015037897;4218.388333691628;4386.6929314947965;32296;32622;32807.3438531228;32872.178195545064;32905.50641740376;4251.2347777400055;4454.532274111204;4517.412736344254;4526.773661316921;4662.044280922402;335102;336949;337278;337473;337757;36365.81817090898;37850.62149378471;64896;65165.34834651593;65586.08104392189;38585.80707096447;38671;38813.223735525135;38831.805840970614;38835.805820970716;15534.300956456986;15543.922280388751;15621.843781562266;15627.843721562866;15628.3748650055;11975.341356225317;13109.541166059333;13372.665683357995;13375;13616.523421680193;8835.072317406708;8860.822783544292;8865.911340886509;8931.106889311146;8964.327675424369;3438.7764795288226;3442.3287458945265;3473.149078475739;3493.825308734604;3493.9301213975577;1111.6053800900563;1116.1628778416189;1129.853119094529;1129.9999500442168;1165.2542372881194;32404.351912961607;32752.181195470304;32996.010119696446;33037.3392532148;33246.47577972127;1196.4376742930808;1210.8668046514877;1213.7147770273978;1267.8351814264272;1312.4422120598708;17184.914075429544;17398.304067837144;17460;17502.737458938125;17635.911820440815;499899;504098;504674;504853;506459;57453;58076.70961645165;58081.70959145177;58101.70949145312;58155.709221453624;1307.1438207973792;1437.482506297732;1461.5250043735828;1474.7492926202572;1522.611734007843;30580.847095765268;34241;34431.65568344285;34908;35159.04760954789;52325.21512177397;53122.20316695175;53171.46828531665;53200.2019969693;53275.733621331645;427034;435728;437018;437282;439808;39031.804840975616;43675.34486982761;43838.34242486429;45049.54950450453;45257.77371113255;47854.760726196146;47947.28079078747;48123.278150827064;48449.757751212426;49029.26456103232;40028.599714002485;40975;41525.58474415217;41676.13240070963;42123;40396.79801600973;40889.18221635491;41072.383914240716;41436.1712765749;41863.79068104762;38057.61942380541;40427.1914561713;40578;41636.167276653694;42113.789431052646;34346.65653343435;36321.63678363288;36449;36862.262754745294;37143.814280928425;28112.71887281101;28153;28233.576496352984;28236.71763282423;28300.292492687026 20:36:21 INFO - PROCESS | 3375 | |2;jquery.html;409.65793562375416;411.7303166425991;412.6842965131673;414.2253985047957;417.72638921506405;13830.861691383083;18650.626987460244;18702.90648546759;19341.419757407282;19345.22619095238;15027.398904043825;15098.622534436645;15465.226738663077;18158.09209539521;18492.167852446637;5541.806036788713;5645.407232240617;5691.573132015094;5736.397678243787;5737.713114344266;5572.749226284806;5664.433556644344;5679.375268720458;5705.77176912923;5727.484526392624;3992.6207010333974;5646.26598542191;5652.321721393439;5653.406392328808;5799.739011744482;6106.267247930233;6127;6238.65868838202;6304.684765761739;6306.558540902139;5367.06076436626;5524.751386187591;5532.640378375433;5533.03171944908;5533.806316778933;22709.54580908389;22748.88625556878;22886.885565572233;23585.41036474102;23744.525109497885;2423.551642946043;2486.4405508760383;2521.9747802521924;2595.3381887618725;2914.810537315068;14937.850621493862;15213.695726085527;15286.770698439532;15824.129672867988;15956;1995.6108558831033;2101.506146055676;2304.884755762222;2326.2090889097617;2333.183385814968;70003.2999670007;73579.63210183968;96414.51792741062;99103;101941.45146371325;16572.17139143038;17481.650366992842;17577;17591.29634814619;17889.463316100537;31067.378652427276;31660.841695791372;31680.207994800316;31686.36627267442;31780.523292150636;9703.5148242589;9768.413895166314;9812.264080194014;10053.64812231583;10329.586816527255;8594.78513037179;8627.482351058956;8633.136686331316;8637.309015278763;8702.434341767765;8644.91355086441;8761.729549215455;8852.159044890854;9022.368434209673;9119.817603648022;3369.949450758241;3418.9399688461217;3480.686738193575;3498.2653642734804;3575.175611267122;991.2763682511662;1045.1899777672365;1065.0042210533177;1099.3898386395467;1135.8012347839094;18261.634767304844;18544.907275463538;19054.809451905305;19161.52096197606;19411.223551058083;1053.8735351757684;1082.3884505254507;1086.8913108689069;1093.1364222264322;1145.7364806094479;10350.948245258725;10813.459327033617;10893.67318980416;10952.397618131079;10999.23005389631;497841;511730;528328;535923;539457;53945;94651.52674236585;97289.5135524332;98669;99035;1061.628430049492;1079.9514021868877;1106.3583121789343;1130.6777568392968;1131.4569006876745;16561.585960350607;16912.154392280583;17094.829051709323;17181.140942953054;17577.121143942495;39442.211155777295;40560.59439405568;40672.38991415072;40930.59069409268;42370.36444453274;51332.48667513426;51787.74106129445;54616.453835461136;56833.71583142058;58707.412925870194;8571.871421928552;8680.869786953075;8748.343874209333;8789.516576588348;9040.638374464948;7147.821304467222;11243.831342529706;12970.35148242603;13126.868731312565;13188.604341869759;10038.548265327932;11066.778664426505;11432.028277596497;11584.94207528957;11647.359395233005;11301.830472543083;11355.943220283845;11471.483783229942;11601.767964640829;11738.119641027128;11217.214794964275;11257.662270131908;11745.29528228309;12117.27296362221;12176.817347739614;7559.697612095674;7901.565413902291;8561.743147705578;8590.699325523577;8679.262262707489;11756.294622322688;11928.940355298168;11996.58011969577;12005.099617528545;12282.692932676755 20:36:21 INFO - PROCESS | 3375 | |3;mootools.html;1391.3391139208884;1522.6269563956832;1567.4357231396698;1751.430784994877;1929.0740444586606;8603.91396086039;8978.685745998891;8980.01219865815;9191.080891910806;9213.815723685522;8952.060033696449;9169.633214671405;9170.587323570438;9179.77050573736;9185.862212066824;2080.3862860456125;2154.7522034966023;2158.9244376446827;2345.155743932183;2385.594448943684;2396.4128788446856;2471.3821544613847;2472.134752836511;2481.9875900620473;2487.9751202488014;2370.1822871109525;2389.390705370128;2390.3307074019303;2395.9760402396014;2430.902763889442;1935.5548223908438;1999.7700264469481;2383.1063351243283;2419.6491508731224;2419.7822196002357;2074;2273.6589511573397;2343.6718859359703;2353.341064501942;2371.0515793682493;8961.327900407496;8973.730788076367;9039.141281578239;9047.728568142966;9049.185573298435;5297.788088476456;5417.756200970965;5441.075017247077;5442.809501667462;5443.455654434564;3485.163560745428;3515.033365824393;3528;3545.4859045438266;3557.918617608702;1157.2651366382324;1171.37916904041;1190.9761804763857;1212.872648371917;1216.799228127358;13071.41178646963;13086.149400289047;13098.214107153506;13111.60665180046;13129.409176587076;4658.532562242883;4735.905281894412;4772.928406073912;4791.233402655558;4817.975910120427;10812.729681758019;10814.080803131665;10859.619913302997;10865.402402867918;10879.782404351869;5432.483914028161;5438.2386465894015;5480.7533660985355;5494.2857428534835;5509.531689806411;4447.510773814878;4827.25177597473;4885.853424397274;4949.7030178189425;4990.975045124824;4032.838686452568;4090.141070375195;4109.815058322323;4185.079282557833;4188.204241194163;1283.8074288856747;1296.3842174966815;1302.7459645369063;1306.0857399820163;1308.188922867828;963.6386355116831;1027.7173777211178;1046.6441409920662;1049.1134990932649;1049.2235745548392;5954.910676339859;6026.638401695912;6111.022236442148;6122.571420000646;6314.55798094129;495.89834084012693;496.1738705056113;498.7456397237435;500.3570412020609;505.2169137836339;4231;4286.914261714748;4296.828126874952;4320.675949303814;4409.757463339483;10866.67399978002;11264.042556382636;11349.432528373549;11363.090952723762;11373.431328433546;10602.204834637432;10861.402622855661;10951.780964380669;11144.944275278573;11290.435478226056;8316.792080198044;8361.790955226046;8383.371247156365;8507.787305317293;8607.784805379915;6136.417040381157;6137.877242455215;6163.7842675506145;6207.503399728102;6211.2857021443115;10249.180065594883;10253.948730256301;10328.483575821328;10456.268061235945;10464;10326.793464130826;10401.739956501147;10579.682609521727;10671.253012289066;10698.251122421356;7390.224026477143;7657.119431265253;7659.463837531319;7822.765317040498;7952;8472.83054338922;8703.651853926025;8714.825703486022;8919.598618062073;8922.910770892207;7900.525968441912;8079.515229086273;8106.259889487498;8165;8169.591520424074;7878.290953814184;7954.960225198837;8026.036875574969;8085.8787118192095;8138.715144969898;6908.067410899665;7119.501634885508;7266.96366518164;7325.157606875277;7342.449316301192;5309.415964244007;5378.058839702958;5409.486098820606;5439.0753571893665;5454.863628409322;5229.424763276113;5243.449437809128;5286.755741335155;5336.786528538815;5578.832635020955 20:36:21 INFO - PROCESS | 3375 | |4;prototype.html;339.9898003059908;344.39214785902885;349.56828317028476;351.917299434633;353.48920809430354;5346.3584369875625;5399.838004859856;5415.9458405415935;5417.160340147283;5417.26866872972;5289.153735402337;5305.7877684892555;5321.228421878825;5372.749486560623;5377.381601115872;3909.866138819739;3963.8810835674904;3977.9204415911663;4033;4058.533268674096;4182.996080940583;4183.686223533232;4189.549113768449;4198.244316023115;4201.8654963159925;4123.917521649565;4129.504459464853;4144.357624568197;4152.875413737593;4196.853110141131;3944.23087497938;4085.59144085592;4186.476690413699;4186.497620285555;4222.303319952204;3716.0709822544363;3741.289155060557;3746.756460830037;3754.3242216401036;3763.209725957552;5154.355705536808;5155.484451554852;5164.896702065976;5184.988927159206;5202.569293444333;4689.859304220879;4733.976330118362;4756.167670657621;4775.928361074587;4787.976060119712;5529.645236916941;5536.640118392332;5553.528679737478;5554.805581804618;5557.72211389433;4105;4106.507219133723;4106.650934670557;4107.096438783463;4118.258713431581;5687.829365119053;5751.7699292028265;5770.7403166857175;5823.359430462688;5836;4174.728642638219;4179.644730197906;4191.3293872980175;4204.516480604709;4206.705530612828;4214.220369231702;4234.957650423518;4237.131388065439;4250.617444430016;4252.4046633471335;4665;4800.615950723934;4823.421189457287;4830.903381932412;4840.733759643183;5578.912112138175;5609.074502707089;5615.8876822464135;5622.875424915033;5630.690312032878;1438.2377340009812;1459.4381163252224;1462.5466105507326;1464.8022516960286;1479.1494890437825;919.2921450483091;955.8661787349638;963.6557002980802;965.7730433348157;980.1815484070738;4754.881127971828;4789.492084103309;4816.759162041884;4860.343853579792;4899.510048995072;5023.447420783711;5033.3205017322925;5038.521340472649;5075.8223462179385;5092.2361645753435;4589.10512450069;4802.199175309259;4844.539768721966;4845.854624361275;4873.878153046202;2727.236373815335;2729;2733.221032005869;2777.8888844445996;2780.7636350910398;5290.629655924048;5294;5360.812371566976;5370.597205209547;5411.864703382368;4853.174960256694;5155.536001759785;5160.716160611202;5190.78171533644;5556.527695145877;5798.9130163047585;5880.441358070976;5986.862496125722;6031.42701443371;6041.75832966694;5780.797672081457;5789.623674461147;5797.43469263305;5823.679697616672;5825.359210486928;5357.223202635674;5674.2623458950075;5689.203511508475;5744.827655170351;5767.961766882002;5977.043673012297;6052.757889684363;6056.939430605814;6060.818175454743;6072.423119803611;4186.958130418657;4196.2656535105625;4199.223143718422;4200.4539409876525;4201.369794530845;5529.368836193413;5707.145177817309;5733.885322293614;5784.652920824764;5796.362400136066;5582.023145949362;5615.922108819763;5629.211910332639;5639.520640745583;5647.1529270609735;5307.4692530746615;5558;5598.880223955144;5621.381648018633;5633;5563.638363506521;5574.303212098488;5602.52378547828;5602.795398989237;5605.887882242251;5718.51392631631;5730.223630674504;5730.596003979052;5759.51044161251;5762.818622182526;5416.322959630046;5477.780888764405;5550.222968784293;5570.2480165177185;5602.915956260741 20:36:21 INFO - PROCESS | 3375 | |5;yui.html;742.5247841381513;807.4630370803415;823.7569916874521;880.2649787427498;896.7489103051148;13756;14364.78452823206;14364.784528232085;14371.35328910199;14371.425142994281;13700.177989320648;13746.175229486213;13759.58721238362;13759.931200343985;13770;682.2699711308901;799.6161842315692;837.1293854391425;837.4221786967004;839.529863276564;838.5304229631396;844.5861527851339;846.6444093480753;846.6909578004046;847.4322204123253;892.8526793079134;895.400081945095;896.6413434626137;896.753392816974;896.9327300452492;717.8815495443246;870.0907551608552;896.1844721303647;896.4890012692767;897.3808072430003;640.3020707428878;641.1888960465016;649.6232185332495;650.2911826109516;650.3756393861851;14486.710265794625;14494.057886237466;14532;14580.781288280685;14582.854171458363;14293.428262869473;14513.492027778977;14517.70964580713;14535.418583256656;14567.635809104751;11123;11506.654800356002;11717.355545444998;11921.761564768656;12548.1216314858;4384.430024096879;4500.797464114123;4510.842120525798;4521.502634710179;4529.071540334224;54039.43213744056;54969.72515137439;55747;56379;56459.71770141164;10970.957759012881;11268.887311126948;11504.712382190512;11723.648290551297;11795.764084718257;19924.800751992585;20002.69995950062;20026.99865006744;20034.69947950782;20073.498162546053;8311.833763324821;8473.195046470575;8556.10160933093;8576.785580360542;8895.332850036144;4836.419629644395;4847.2729090635985;4873.707577545288;4889.217725163957;5049.0210775642545;3370.5112758649857;3468.0462872710204;3475.4439289713523;3476.200473891019;3618.565772107364;639.2744235292976;686;695.3776370148789;698.1133959870936;722.0901663903414;648.2852854200707;661.3386613386614;692.1452006771633;696.467202590019;739.663453128825;9046.321525885585;9520.666776662783;9541.856872146924;9600.087991640783;9642.228621710246;709.095902724031;731.5683746589538;736.8832312082417;740.1784019738086;762.1768490030754;4443.977780111079;4473.530279320689;4518.548145185521;4704.88237794051;4715.4577223619035;53242;54188.72905635447;54688.726556366964;63868;69192.6540367295;54094;54273;54684.453155467934;55167;55508;50095.24857127146;50707.23939140916;50735;51447.48552514427;52131.73934130381;8639.784005399915;8895.243904268209;9020;9112.190003909634;9321.627134914528;34484.310313794085;34650.30699386049;35157;35201.47197791984;35276.47085293671;40745.79627101846;43012.13975720531;43886.34170487509;44026.559734402246;44950.77524612356;11997.340146292037;12039.819402708792;12047.879521205026;12073.215241009306;12234.816477753018;12871.099023068295;12933.159344642569;12935.805962910375;13550.322483875963;13850.792238116235;12287.877121228674;12450.875491244971;12456;12469.625911222678;12561.724857864569;12270;12444.320712695122;12598.181118227287;12610.243385396905;12646.43091060886;12399.690007749878;12789.42447589842;12871.742565148457;12940.8058879115;12998.285094319903;9806.509674516388;9842.60629574809;9924.851127232952;9938.214411352204;10081.899181008095;9776.706698799047;10164.796704066026;10335.896641033794;10340.741481463023;10350.430726310125 20:36:21 INFO - PROCESS | 3375 | __end_tp_report 20:36:21 INFO - PROCESS | 3375 | __start_cc_report 20:36:21 INFO - PROCESS | 3375 | _x_x_mozilla_cycle_collect,1105 20:36:21 INFO - PROCESS | 3375 | __end_cc_report 20:36:21 INFO - PROCESS | 3375 | __startTimestamp1463110581861__endTimestamp 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | ------- Summary: start ------- 20:36:21 INFO - PROCESS | 3375 | Number of tests: 6 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | [#0] dojo.html Cycles:165 Average:5326.34 Median:5523.59 stddev:614.55 (11.1%) stddev-sans-first:592.06 20:36:21 INFO - PROCESS | 3375 | Values: 3142.1 3155.7 3173.3 3206.6 3235.6 5287.5 5319.3 5374.4 5394.6 5409.6 5365.2 5390.6 5402.0 5469.9 5505.0 4807.4 4830.8 4855.8 4872.5 4876.7 4888.4 4907.7 4909.6 4945.2 4949.3 4755.1 4823.2 4863.4 4886.6 4899.1 4750.6 4781.1 4790.4 4828.4 4866.9 4739.8 4799.0 4801.1 4810.0 4839.2 5171.2 5221.3 5229.3 5305.2 5318.2 5659.9 5727.9 5767.9 5786.6 5788.4 5758.3 5761.5 5778.5 5856.0 5857.9 5265.7 5348.5 5377.7 5381.1 5421.6 5959.8 6005.8 6008.1 6008.1 6018.6 5749.2 5751.5 5752.9 5758.1 5759.4 5496.9 5639.6 5693.7 5699.9 5780.2 5705.9 5708.8 5713.1 5716.6 5717.3 5696.5 5716.3 5719.7 5739.0 5744.1 4281.6 4336.0 4345.2 4347.3 4371.8 3890.7 3894.3 3898.3 3935.0 3945.4 5473.4 5492.5 5506.9 5523.6 5524.4 5579.3 5605.3 5626.4 5636.1 5653.9 5362.7 5388.4 5399.7 5403.3 5406.9 4733.7 4761.1 4782.1 4786.7 4812.4 5934.1 5968.9 5994.2 6020.1 6027.0 5777.4 5782.9 5788.5 5790.6 5793.5 5799.6 5891.9 6000.0 6021.0 6027.2 5619.5 5633.0 5669.9 5670.6 5709.8 5743.3 5750.9 5764.4 5765.3 5770.0 5414.5 5592.1 5619.9 5624.3 5626.2 5657.9 5662.9 5663.4 5674.3 5721.9 5511.5 5538.0 5561.7 5578.8 5582.6 5481.5 5512.1 5601.2 5667.7 5684.8 5427.1 5505.5 5521.1 5523.0 5524.0 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | [#1] ext.html Cycles:180 Average:58785.58 Median:21207.14 stddev:114041.39 (537.8%) stddev-sans-first:114277.62 20:36:21 INFO - PROCESS | 3375 | Values: 596.6 635.8 648.5 657.8 662.2 43853.0 45585.3 45702.3 45717.1 48686.0 46323.3 46683.0 46698.8 46754.0 46799.8 8072.6 8940.6 9081.0 9092.6 10631.3 10367.4 10751.5 10754.5 10765.4 10774.1 10815.9 10860.3 10869.3 10872.9 10877.4 8425.8 10563.9 10758.6 10774.6 10839.2 10282.2 10726.7 10731.1 10737.4 10788.1 41846.4 42321.8 42325.4 42337.4 42347.4 3613.8 3711.5 3828.5 4218.4 4386.7 32296.0 32622.0 32807.3 32872.2 32905.5 4251.2 4454.5 4517.4 4526.8 4662.0 335102.0 336949.0 337278.0 337473.0 337757.0 36365.8 37850.6 64896.0 65165.3 65586.1 38585.8 38671.0 38813.2 38831.8 38835.8 15534.3 15543.9 15621.8 15627.8 15628.4 11975.3 13109.5 13372.7 13375.0 13616.5 8835.1 8860.8 8865.9 8931.1 8964.3 3438.8 3442.3 3473.1 3493.8 3493.9 1111.6 1116.2 1129.9 1130.0 1165.3 32404.4 32752.2 32996.0 33037.3 33246.5 1196.4 1210.9 1213.7 1267.8 1312.4 17184.9 17398.3 17460.0 17502.7 17635.9 499899.0 504098.0 504674.0 504853.0 506459.0 57453.0 58076.7 58081.7 58101.7 58155.7 1307.1 1437.5 1461.5 1474.7 1522.6 30580.8 34241.0 34431.7 34908.0 35159.0 52325.2 53122.2 53171.5 53200.2 53275.7 427034.0 435728.0 437018.0 437282.0 439808.0 39031.8 43675.3 43838.3 45049.5 45257.8 47854.8 47947.3 48123.3 48449.8 49029.3 40028.6 40975.0 41525.6 41676.1 42123.0 40396.8 40889.2 41072.4 41436.2 41863.8 38057.6 40427.2 40578.0 41636.2 42113.8 34346.7 36321.6 36449.0 36862.3 37143.8 28112.7 28153.0 28233.6 28236.7 28300.3 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | [#2] jquery.html Cycles:180 Average:30735.89 Median:16991.09 stddev:86021.57 (506.3%) stddev-sans-first:86232.74 20:36:21 INFO - PROCESS | 3375 | Values: 409.7 411.7 412.7 414.2 417.7 13830.9 18650.6 18702.9 19341.4 19345.2 15027.4 15098.6 15465.2 18158.1 18492.2 5541.8 5645.4 5691.6 5736.4 5737.7 5572.7 5664.4 5679.4 5705.8 5727.5 3992.6 5646.3 5652.3 5653.4 5799.7 6106.3 6127.0 6238.7 6304.7 6306.6 5367.1 5524.8 5532.6 5533.0 5533.8 22709.5 22748.9 22886.9 23585.4 23744.5 2423.6 2486.4 2522.0 2595.3 2914.8 14937.9 15213.7 15286.8 15824.1 15956.0 1995.6 2101.5 2304.9 2326.2 2333.2 70003.3 73579.6 96414.5 99103.0 101941.5 16572.2 17481.7 17577.0 17591.3 17889.5 31067.4 31660.8 31680.2 31686.4 31780.5 9703.5 9768.4 9812.3 10053.6 10329.6 8594.8 8627.5 8633.1 8637.3 8702.4 8644.9 8761.7 8852.2 9022.4 9119.8 3369.9 3418.9 3480.7 3498.3 3575.2 991.3 1045.2 1065.0 1099.4 1135.8 18261.6 18544.9 19054.8 19161.5 19411.2 1053.9 1082.4 1086.9 1093.1 1145.7 10350.9 10813.5 10893.7 10952.4 10999.2 497841.0 511730.0 528328.0 535923.0 539457.0 53945.0 94651.5 97289.5 98669.0 99035.0 1061.6 1080.0 1106.4 1130.7 1131.5 16561.6 16912.2 17094.8 17181.1 17577.1 39442.2 40560.6 40672.4 40930.6 42370.4 51332.5 51787.7 54616.5 56833.7 58707.4 8571.9 8680.9 8748.3 8789.5 9040.6 7147.8 11243.8 12970.4 13126.9 13188.6 10038.5 11066.8 11432.0 11584.9 11647.4 11301.8 11355.9 11471.5 11601.8 11738.1 11217.2 11257.7 11745.3 12117.3 12176.8 7559.7 7901.6 8561.7 8590.7 8679.3 11756.3 11928.9 11996.6 12005.1 12282.7 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | [#3] mootools.html Cycles:180 Average:5972.89 Median:4697.22 stddev:3441.82 (73.3%) stddev-sans-first:3434.25 20:36:21 INFO - PROCESS | 3375 | Values: 1391.3 1522.6 1567.4 1751.4 1929.1 8603.9 8978.7 8980.0 9191.1 9213.8 8952.1 9169.6 9170.6 9179.8 9185.9 2080.4 2154.8 2158.9 2345.2 2385.6 2396.4 2471.4 2472.1 2482.0 2488.0 2370.2 2389.4 2390.3 2396.0 2430.9 1935.6 1999.8 2383.1 2419.6 2419.8 2074.0 2273.7 2343.7 2353.3 2371.1 8961.3 8973.7 9039.1 9047.7 9049.2 5297.8 5417.8 5441.1 5442.8 5443.5 3485.2 3515.0 3528.0 3545.5 3557.9 1157.3 1171.4 1191.0 1212.9 1216.8 13071.4 13086.1 13098.2 13111.6 13129.4 4658.5 4735.9 4772.9 4791.2 4818.0 10812.7 10814.1 10859.6 10865.4 10879.8 5432.5 5438.2 5480.8 5494.3 5509.5 4447.5 4827.3 4885.9 4949.7 4991.0 4032.8 4090.1 4109.8 4185.1 4188.2 1283.8 1296.4 1302.7 1306.1 1308.2 963.6 1027.7 1046.6 1049.1 1049.2 5954.9 6026.6 6111.0 6122.6 6314.6 495.9 496.2 498.7 500.4 505.2 4231.0 4286.9 4296.8 4320.7 4409.8 10866.7 11264.0 11349.4 11363.1 11373.4 10602.2 10861.4 10951.8 11144.9 11290.4 8316.8 8361.8 8383.4 8507.8 8607.8 6136.4 6137.9 6163.8 6207.5 6211.3 10249.2 10253.9 10328.5 10456.3 10464.0 10326.8 10401.7 10579.7 10671.3 10698.3 7390.2 7657.1 7659.5 7822.8 7952.0 8472.8 8703.7 8714.8 8919.6 8922.9 7900.5 8079.5 8106.3 8165.0 8169.6 7878.3 7955.0 8026.0 8085.9 8138.7 6908.1 7119.5 7267.0 7325.2 7342.4 5309.4 5378.1 5409.5 5439.1 5454.9 5229.4 5243.4 5286.8 5336.8 5578.8 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | [#4] prototype.html Cycles:180 Average:4644.33 Median:5174.94 stddev:1356.90 (26.2%) stddev-sans-first:1321.69 20:36:21 INFO - PROCESS | 3375 | Values: 340.0 344.4 349.6 351.9 353.5 5346.4 5399.8 5415.9 5417.2 5417.3 5289.2 5305.8 5321.2 5372.7 5377.4 3909.9 3963.9 3977.9 4033.0 4058.5 4183.0 4183.7 4189.5 4198.2 4201.9 4123.9 4129.5 4144.4 4152.9 4196.9 3944.2 4085.6 4186.5 4186.5 4222.3 3716.1 3741.3 3746.8 3754.3 3763.2 5154.4 5155.5 5164.9 5185.0 5202.6 4689.9 4734.0 4756.2 4775.9 4788.0 5529.6 5536.6 5553.5 5554.8 5557.7 4105.0 4106.5 4106.7 4107.1 4118.3 5687.8 5751.8 5770.7 5823.4 5836.0 4174.7 4179.6 4191.3 4204.5 4206.7 4214.2 4235.0 4237.1 4250.6 4252.4 4665.0 4800.6 4823.4 4830.9 4840.7 5578.9 5609.1 5615.9 5622.9 5630.7 1438.2 1459.4 1462.5 1464.8 1479.1 919.3 955.9 963.7 965.8 980.2 4754.9 4789.5 4816.8 4860.3 4899.5 5023.4 5033.3 5038.5 5075.8 5092.2 4589.1 4802.2 4844.5 4845.9 4873.9 2727.2 2729.0 2733.2 2777.9 2780.8 5290.6 5294.0 5360.8 5370.6 5411.9 4853.2 5155.5 5160.7 5190.8 5556.5 5798.9 5880.4 5986.9 6031.4 6041.8 5780.8 5789.6 5797.4 5823.7 5825.4 5357.2 5674.3 5689.2 5744.8 5768.0 5977.0 6052.8 6056.9 6060.8 6072.4 4187.0 4196.3 4199.2 4200.5 4201.4 5529.4 5707.1 5733.9 5784.7 5796.4 5582.0 5615.9 5629.2 5639.5 5647.2 5307.5 5558.0 5598.9 5621.4 5633.0 5563.6 5574.3 5602.5 5602.8 5605.9 5718.5 5730.2 5730.6 5759.5 5762.8 5416.3 5477.8 5550.2 5570.2 5602.9 20:36:21 INFO - PROCESS | 3375 | 20:36:21 INFO - PROCESS | 3375 | [#5] yui.html Cycles:180 Average:14818.84 Median:4841.85 stddev:16891.51 (348.9%) stddev-sans-first:16905.82 20:36:21 INFO - PROCESS | 3375 | Values: 742.5 807.5 823.8 880.3 896.7 13756.0 14364.8 14364.8 14371.4 14371.4 13700.2 13746.2 13759.6 13759.9 13770.0 682.3 799.6 837.1 837.4 839.5 838.5 844.6 846.6 846.7 847.4 892.9 895.4 896.6 896.8 896.9 717.9 870.1 896.2 896.5 897.4 640.3 641.2 649.6 650.3 650.4 14486.7 14494.1 14532.0 14580.8 14582.9 14293.4 14513.5 14517.7 14535.4 14567.6 11123.0 11506.7 11717.4 11921.8 12548.1 4384.4 4500.8 4510.8 4521.5 4529.1 54039.4 54969.7 55747.0 56379.0 56459.7 10971.0 11268.9 11504.7 11723.6 11795.8 19924.8 20002.7 20027.0 20034.7 20073.5 8311.8 8473.2 8556.1 8576.8 8895.3 4836.4 4847.3 4873.7 4889.2 5049.0 3370.5 3468.0 3475.4 3476.2 3618.6 639.3 686.0 695.4 698.1 722.1 648.3 661.3 692.1 696.5 739.7 9046.3 9520.7 9541.9 9600.1 9642.2 709.1 731.6 736.9 740.2 762.2 4444.0 4473.5 4518.5 4704.9 4715.5 53242.0 54188.7 54688.7 63868.0 69192.7 54094.0 54273.0 54684.5 55167.0 55508.0 50095.2 50707.2 50735.0 51447.5 52131.7 8639.8 8895.2 9020.0 9112.2 9321.6 34484.3 34650.3 35157.0 35201.5 35276.5 40745.8 43012.1 43886.3 44026.6 44950.8 11997.3 12039.8 12047.9 12073.2 12234.8 12871.1 12933.2 12935.8 13550.3 13850.8 12287.9 12450.9 12456.0 12469.6 12561.7 12270.0 12444.3 12598.2 12610.2 12646.4 12399.7 12789.4 12871.7 12940.8 12998.3 9806.5 9842.6 9924.9 9938.2 10081.9 9776.7 10164.8 10335.9 10340.7 10350.4 20:36:21 INFO - PROCESS | 3375 | -------- Summary: end -------- 20:36:21 INFO - PROCESS | 3375 | 20:36:22 INFO - TEST-INFO | 3375: exit 0 20:36:22 INFO - TEST-OK | dromaeo_css | took 1119275ms 20:36:22 INFO - TEST-START | kraken 20:36:22 INFO - operating with platform_type : linux_ 20:36:22 INFO - Initialising browser for kraken test... 20:36:22 INFO - TEST-INFO | started process 3556 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZMk1z/profile http://localhost:47440/getInfo.html) 20:36:27 INFO - PROCESS | 3556 | __metrics Screen width/height:1600/1200 20:36:27 INFO - PROCESS | 3556 | colorDepth:24 20:36:27 INFO - PROCESS | 3556 | Browser inner width/height: 1024/697 20:36:27 INFO - PROCESS | 3556 | __metrics 20:36:28 ERROR - PROCESS | 3556 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:36:29 INFO - TEST-INFO | 3556: exit 0 20:36:29 INFO - Browser initialized. 20:36:29 INFO - Running cycle 1/1 for kraken test... 20:36:29 INFO - TEST-INFO | started process 3638 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZMk1z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:36:41 INFO - PROCESS | 3638 | RSS: Main: 152268800 20:36:41 INFO - PROCESS | 3638 | 20:36:44 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-beat-detection) 20:36:45 INFO - PROCESS | 3638 | RSS: Main: 208801792 20:36:45 INFO - PROCESS | 3638 | 20:36:47 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-dft) 20:36:48 INFO - PROCESS | 3638 | RSS: Main: 196476928 20:36:48 INFO - PROCESS | 3638 | 20:36:51 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-fft) 20:36:52 INFO - PROCESS | 3638 | RSS: Main: 265715712 20:36:52 INFO - PROCESS | 3638 | 20:36:54 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-oscillator) 20:36:54 INFO - PROCESS | 3638 | RSS: Main: 174735360 20:36:54 INFO - PROCESS | 3638 | 20:36:57 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47440/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:36:57 INFO - PROCESS | 3638 | RSS: Main: 179326976 20:36:57 INFO - PROCESS | 3638 | 20:37:01 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47440/tests/kraken/driver.html?testName=imaging-darkroom) 20:37:01 INFO - PROCESS | 3638 | RSS: Main: 214515712 20:37:01 INFO - PROCESS | 3638 | 20:37:05 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47440/tests/kraken/driver.html?testName=imaging-desaturate) 20:37:05 INFO - PROCESS | 3638 | RSS: Main: 212848640 20:37:05 INFO - PROCESS | 3638 | 20:37:08 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47440/tests/kraken/driver.html?testName=json-parse-financial) 20:37:09 INFO - PROCESS | 3638 | RSS: Main: 214351872 20:37:09 INFO - PROCESS | 3638 | 20:37:11 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47440/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:37:12 INFO - PROCESS | 3638 | RSS: Main: 206229504 20:37:13 INFO - PROCESS | 3638 | 20:37:14 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:37:14 INFO - PROCESS | 3638 | RSS: Main: 209272832 20:37:14 INFO - PROCESS | 3638 | 20:37:16 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:37:17 INFO - PROCESS | 3638 | RSS: Main: 217411584 20:37:17 INFO - PROCESS | 3638 | 20:37:21 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:37:21 INFO - PROCESS | 3638 | RSS: Main: 218505216 20:37:21 INFO - PROCESS | 3638 | 20:37:24 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:37:25 INFO - PROCESS | 3638 | RSS: Main: 218349568 20:37:25 INFO - PROCESS | 3638 | 20:37:26 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47440/tests/kraken/driver.html?testName=ai-astar) 20:37:27 INFO - PROCESS | 3638 | RSS: Main: 263946240 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | __start_tp_report 20:37:27 INFO - PROCESS | 3638 | _x_x_mozilla_page_load 20:37:27 INFO - PROCESS | 3638 | _x_x_mozilla_page_load_details 20:37:27 INFO - PROCESS | 3638 | |i|pagename|runs| 20:37:27 INFO - PROCESS | 3638 | |0;ai-astar;110;105;110;109;108;110;109;109;109;109 20:37:27 INFO - PROCESS | 3638 | |1;audio-beat-detection;156;123;153;209;126;173;156;125;189;124 20:37:27 INFO - PROCESS | 3638 | |2;audio-dft;161;161;160;161;159;160;161;160;161;160 20:37:27 INFO - PROCESS | 3638 | |3;audio-fft;74;75;75;153;76;74;77;75;132;76 20:37:27 INFO - PROCESS | 3638 | |4;audio-oscillator;92;93;153;92;91;92;128;93;92;139 20:37:27 INFO - PROCESS | 3638 | |5;imaging-gaussian-blur;117;117;117;117;116;117;118;116;117;117 20:37:27 INFO - PROCESS | 3638 | |6;imaging-darkroom;148;146;146;147;147;145;145;145;146;145 20:37:27 INFO - PROCESS | 3638 | |7;imaging-desaturate;94;95;94;95;95;94;95;95;95;94 20:37:27 INFO - PROCESS | 3638 | |8;json-parse-financial;56;58;58;56;57;56;57;109;56;56 20:37:27 INFO - PROCESS | 3638 | |9;json-stringify-tinderbox;60;60;59;60;60;59;60;59;60;60 20:37:27 INFO - PROCESS | 3638 | |10;stanford-crypto-aes;80;79;79;78;79;86;80;81;81;82 20:37:27 INFO - PROCESS | 3638 | |11;stanford-crypto-ccm;133;128;124;124;121;123;123;117;203;128 20:37:27 INFO - PROCESS | 3638 | |12;stanford-crypto-pbkdf2;166;167;165;166;168;168;168;167;167;166 20:37:27 INFO - PROCESS | 3638 | |13;stanford-crypto-sha256-iterative;65;64;65;67;64;64;64;63;64;64 20:37:27 INFO - PROCESS | 3638 | __end_tp_report 20:37:27 INFO - PROCESS | 3638 | __start_cc_report 20:37:27 INFO - PROCESS | 3638 | _x_x_mozilla_cycle_collect,1829 20:37:27 INFO - PROCESS | 3638 | __end_cc_report 20:37:27 INFO - PROCESS | 3638 | __startTimestamp1463110647093__endTimestamp 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | ------- Summary: start ------- 20:37:27 INFO - PROCESS | 3638 | Number of tests: 14 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.00 stddev:1.48 (1.4%) stddev-sans-first:1.50 20:37:27 INFO - PROCESS | 3638 | Values: 110.0 105.0 110.0 109.0 108.0 110.0 109.0 109.0 109.0 109.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#1] audio-beat-detection Cycles:10 Average:153.40 Median:156.00 stddev:30.00 (19.2%) stddev-sans-first:31.81 20:37:27 INFO - PROCESS | 3638 | Values: 156.0 123.0 153.0 209.0 126.0 173.0 156.0 125.0 189.0 124.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 20:37:27 INFO - PROCESS | 3638 | Values: 161.0 161.0 160.0 161.0 159.0 160.0 161.0 160.0 161.0 160.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#3] audio-fft Cycles:10 Average:88.70 Median:75.00 stddev:28.80 (38.4%) stddev-sans-first:30.05 20:37:27 INFO - PROCESS | 3638 | Values: 74.0 75.0 75.0 153.0 76.0 74.0 77.0 75.0 132.0 76.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#4] audio-oscillator Cycles:10 Average:106.50 Median:92.00 stddev:23.87 (25.9%) stddev-sans-first:24.73 20:37:27 INFO - PROCESS | 3638 | Values: 92.0 93.0 153.0 92.0 91.0 92.0 128.0 93.0 92.0 139.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 20:37:27 INFO - PROCESS | 3638 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 118.0 116.0 117.0 117.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.05 (0.7%) stddev-sans-first:0.83 20:37:27 INFO - PROCESS | 3638 | Values: 148.0 146.0 146.0 147.0 147.0 145.0 145.0 145.0 146.0 145.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 20:37:27 INFO - PROCESS | 3638 | Values: 94.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:56.50 stddev:16.57 (29.3%) stddev-sans-first:17.44 20:37:27 INFO - PROCESS | 3638 | Values: 56.0 58.0 58.0 56.0 57.0 56.0 57.0 109.0 56.0 56.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 20:37:27 INFO - PROCESS | 3638 | Values: 60.0 60.0 59.0 60.0 60.0 59.0 60.0 59.0 60.0 60.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.50 stddev:2.27 (2.8%) stddev-sans-first:2.40 20:37:27 INFO - PROCESS | 3638 | Values: 80.0 79.0 79.0 78.0 79.0 86.0 80.0 81.0 81.0 82.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#11] stanford-crypto-ccm Cycles:10 Average:132.40 Median:126.00 stddev:25.18 (20.0%) stddev-sans-first:26.71 20:37:27 INFO - PROCESS | 3638 | Values: 133.0 128.0 124.0 124.0 121.0 123.0 123.0 117.0 203.0 128.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 20:37:27 INFO - PROCESS | 3638 | Values: 166.0 167.0 165.0 166.0 168.0 168.0 168.0 167.0 167.0 166.0 20:37:27 INFO - PROCESS | 3638 | 20:37:27 INFO - PROCESS | 3638 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.00 stddev:1.07 (1.7%) stddev-sans-first:1.12 20:37:27 INFO - PROCESS | 3638 | Values: 65.0 64.0 65.0 67.0 64.0 64.0 64.0 63.0 64.0 64.0 20:37:27 INFO - PROCESS | 3638 | -------- Summary: end -------- 20:37:27 INFO - PROCESS | 3638 | 20:37:28 INFO - TEST-INFO | 3638: exit 0 20:37:28 INFO - TEST-OK | kraken | took 65476ms 20:37:28 INFO - SUITE-END | took 1184s 20:37:28 INFO - Completed test suite (00:19:44) 20:37:28 INFO - javascript benchmark 20:37:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.0887942496693, 3155.7475401967845, 3173.2542852429683, 3206.5831441912546, 3235.611726592809, 5287.47125287471, 5319.335083114611, 5374.408815030353, 5394.595405344595, 5409.6213265071465, 5365.248865158881, 5390.649607775502, 5402.0, 5469.890602187954, 5505.0, 4807.375041244633, 4830.830920917769, 4855.830045948393, 4872.537108974641, 4876.707397556149, 4888.388951381077, 4907.681000734959, 4909.582685471739, 4945.23348880924, 4949.331840201581, 4755.120302744003, 4823.20417131173, 4863.416390033183, 4886.633502487328, 4899.121153808006, 4750.572448479619, 4781.0676918001, 4790.377250957388, 4828.396450443694, 4866.902661946741, 4739.810407583693, 4798.992211635561, 4801.111794318027, 4810.0, 4839.249916262985, 5171.22431635254, 5221.321228240343, 5229.346331708536, 5305.1776974569, 5318.175682769177, 5659.8585035374035, 5727.942720572824, 5767.855803604902, 5786.566007549451, 5788.363280039192, 5758.337791154029, 5761.510271626917, 5778.451047150514, 5855.970720146414, 5857.941420585782, 5265.736713164365, 5348.5186333230195, 5377.731113444352, 5381.13901775714, 5421.620486565942, 5959.821205363846, 6005.789797357072, 6008.068749343859, 6008.128821320884, 6018.608790428608, 5749.195112684228, 5751.4536119068625, 5752.913706294409, 5758.136279558016, 5759.424057594207, 5496.862578435491, 5639.63342382752, 5693.658380497183, 5699.857503562444, 5780.219670344533, 5705.942940570541, 5708.80019199326, 5713.143028545752, 5716.599838011382, 5717.342505611825, 5696.487316141567, 5716.342620598602, 5719.685417302005, 5739.024365857814, 5744.052231381859, 4281.593248641374, 4336.046069864625, 4345.239583072949, 4347.260965635849, 4371.75955322454, 3890.6692931100606, 3894.2600905827803, 3898.2983063048923, 3934.960650393459, 3945.447637330776, 5473.425290344536, 5492.505674489235, 5506.889862202814, 5523.585731070186, 5524.364698059694, 5579.302587176603, 5605.299337582802, 5626.352969408488, 5636.126400407861, 5653.858653533613, 5362.678239305654, 5388.38033626138, 5399.730013499309, 5403.324584426947, 5406.8918621627345, 4733.71597704132, 4761.072639309796, 4782.115308667908, 4786.736729479912, 4812.398450193726, 5934.139549765347, 5968.850778730566, 5994.190784244159, 6020.066889632114, 6027.0356742921795, 5777.364489906191, 5782.942170578241, 5788.479036886701, 5790.594658373873, 5793.478586927196, 5799.623024503394, 5891.882162356643, 5999.970000149971, 6021.0, 6027.21646185993, 5619.494245517923, 5633.0, 5669.914951275651, 5670.574706896917, 5709.8287051388525, 5743.3395159557185, 5750.856228594152, 5764.42355764437, 5765.250517432707, 5769.9711501442225, 5414.458554144554, 5592.133219351007, 5619.8876022480135, 5624.268845050281, 5626.156076588544, 5657.886842263214, 5662.85842853932, 5663.3770285267765, 5674.319081710056, 5721.914171287517, 5511.5039646432015, 5537.972310138584, 5561.721913904369, 5578.776848925998, 5582.609217354909, 5481.5340696041285, 5512.145617429305, 5601.187827765033, 5667.744951477111, 5684.8294551163535, 5427.05026620332, 5505.532029777513, 5521.1442226454965, 5523.0, 5523.972380138073], "value": 5284.9506347947045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.6241268001162, 635.7965451055662, 648.5265755998121, 657.8355411147213, 662.2152748992447, 43853.0, 45585.316220256726, 45702.314465282965, 45717.085658286815, 48686.0, 46323.30515042277, 46683.0, 46698.76650616751, 46754.0, 46799.76600116995, 8072.55600941948, 8940.64237430502, 9081.0, 9092.63629454821, 10631.308964917294, 10367.377957322547, 10751.51618177184, 10754.462276886163, 10765.407902565395, 10774.084202842767, 10815.945920270387, 10860.294080884756, 10869.293495922779, 10872.945635271813, 10877.401742904138, 8425.83148337033, 10563.947180264126, 10758.569657213702, 10774.5690172393, 10839.18706097038, 10282.177425805918, 10726.731831704192, 10731.141508679366, 10737.40944248066, 10788.136949044136, 41846.372304415156, 42321.788391057846, 42325.36511952323, 42337.364939525934, 42347.36478952818, 3613.819309034564, 3711.480392745018, 3828.464015037897, 4218.388333691628, 4386.6929314947965, 32296.0, 32622.0, 32807.3438531228, 32872.178195545064, 32905.50641740376, 4251.2347777400055, 4454.532274111204, 4517.412736344254, 4526.773661316921, 4662.044280922402, 335102.0, 336949.0, 337278.0, 337473.0, 337757.0, 36365.81817090898, 37850.62149378471, 64896.0, 65165.34834651593, 65586.08104392189, 38585.80707096447, 38671.0, 38813.223735525135, 38831.805840970614, 38835.805820970716, 15534.300956456986, 15543.922280388751, 15621.843781562266, 15627.843721562866, 15628.3748650055, 11975.341356225317, 13109.541166059333, 13372.665683357995, 13375.0, 13616.523421680193, 8835.072317406708, 8860.822783544292, 8865.911340886509, 8931.106889311146, 8964.327675424369, 3438.7764795288226, 3442.3287458945265, 3473.149078475739, 3493.825308734604, 3493.9301213975577, 1111.6053800900563, 1116.1628778416189, 1129.853119094529, 1129.9999500442168, 1165.2542372881194, 32404.351912961607, 32752.181195470304, 32996.010119696446, 33037.3392532148, 33246.47577972127, 1196.4376742930808, 1210.8668046514877, 1213.7147770273978, 1267.8351814264272, 1312.4422120598708, 17184.914075429544, 17398.304067837144, 17460.0, 17502.737458938125, 17635.911820440815, 499899.0, 504098.0, 504674.0, 504853.0, 506459.0, 57453.0, 58076.70961645165, 58081.70959145177, 58101.70949145312, 58155.709221453624, 1307.1438207973792, 1437.482506297732, 1461.5250043735828, 1474.7492926202572, 1522.611734007843, 30580.847095765268, 34241.0, 34431.65568344285, 34908.0, 35159.04760954789, 52325.21512177397, 53122.20316695175, 53171.46828531665, 53200.2019969693, 53275.733621331645, 427034.0, 435728.0, 437018.0, 437282.0, 439808.0, 39031.804840975616, 43675.34486982761, 43838.34242486429, 45049.54950450453, 45257.77371113255, 47854.760726196146, 47947.28079078747, 48123.278150827064, 48449.757751212426, 49029.26456103232, 40028.599714002485, 40975.0, 41525.58474415217, 41676.13240070963, 42123.0, 40396.79801600973, 40889.18221635491, 41072.383914240716, 41436.1712765749, 41863.79068104762, 38057.61942380541, 40427.1914561713, 40578.0, 41636.167276653694, 42113.789431052646, 34346.65653343435, 36321.63678363288, 36449.0, 36862.262754745294, 37143.814280928425, 28112.71887281101, 28153.0, 28233.576496352984, 28236.71763282423, 28300.292492687026], "value": 19991.891033044572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.65793562375416, 411.7303166425991, 412.6842965131673, 414.2253985047957, 417.72638921506405, 13830.861691383083, 18650.626987460244, 18702.90648546759, 19341.419757407282, 19345.22619095238, 15027.398904043825, 15098.622534436645, 15465.226738663077, 18158.09209539521, 18492.167852446637, 5541.806036788713, 5645.407232240617, 5691.573132015094, 5736.397678243787, 5737.713114344266, 5572.749226284806, 5664.433556644344, 5679.375268720458, 5705.77176912923, 5727.484526392624, 3992.6207010333974, 5646.26598542191, 5652.321721393439, 5653.406392328808, 5799.739011744482, 6106.267247930233, 6127.0, 6238.65868838202, 6304.684765761739, 6306.558540902139, 5367.06076436626, 5524.751386187591, 5532.640378375433, 5533.03171944908, 5533.806316778933, 22709.54580908389, 22748.88625556878, 22886.885565572233, 23585.41036474102, 23744.525109497885, 2423.551642946043, 2486.4405508760383, 2521.9747802521924, 2595.3381887618725, 2914.810537315068, 14937.850621493862, 15213.695726085527, 15286.770698439532, 15824.129672867988, 15956.0, 1995.6108558831033, 2101.506146055676, 2304.884755762222, 2326.2090889097617, 2333.183385814968, 70003.2999670007, 73579.63210183968, 96414.51792741062, 99103.0, 101941.45146371325, 16572.17139143038, 17481.650366992842, 17577.0, 17591.29634814619, 17889.463316100537, 31067.378652427276, 31660.841695791372, 31680.207994800316, 31686.36627267442, 31780.523292150636, 9703.5148242589, 9768.413895166314, 9812.264080194014, 10053.64812231583, 10329.586816527255, 8594.78513037179, 8627.482351058956, 8633.136686331316, 8637.309015278763, 8702.434341767765, 8644.91355086441, 8761.729549215455, 8852.159044890854, 9022.368434209673, 9119.817603648022, 3369.949450758241, 3418.9399688461217, 3480.686738193575, 3498.2653642734804, 3575.175611267122, 991.2763682511662, 1045.1899777672365, 1065.0042210533177, 1099.3898386395467, 1135.8012347839094, 18261.634767304844, 18544.907275463538, 19054.809451905305, 19161.52096197606, 19411.223551058083, 1053.8735351757684, 1082.3884505254507, 1086.8913108689069, 1093.1364222264322, 1145.7364806094479, 10350.948245258725, 10813.459327033617, 10893.67318980416, 10952.397618131079, 10999.23005389631, 497841.0, 511730.0, 528328.0, 535923.0, 539457.0, 53945.0, 94651.52674236585, 97289.5135524332, 98669.0, 99035.0, 1061.628430049492, 1079.9514021868877, 1106.3583121789343, 1130.6777568392968, 1131.4569006876745, 16561.585960350607, 16912.154392280583, 17094.829051709323, 17181.140942953054, 17577.121143942495, 39442.211155777295, 40560.59439405568, 40672.38991415072, 40930.59069409268, 42370.36444453274, 51332.48667513426, 51787.74106129445, 54616.453835461136, 56833.71583142058, 58707.412925870194, 8571.871421928552, 8680.869786953075, 8748.343874209333, 8789.516576588348, 9040.638374464948, 7147.821304467222, 11243.831342529706, 12970.35148242603, 13126.868731312565, 13188.604341869759, 10038.548265327932, 11066.778664426505, 11432.028277596497, 11584.94207528957, 11647.359395233005, 11301.830472543083, 11355.943220283845, 11471.483783229942, 11601.767964640829, 11738.119641027128, 11217.214794964275, 11257.662270131908, 11745.29528228309, 12117.27296362221, 12176.817347739614, 7559.697612095674, 7901.565413902291, 8561.743147705578, 8590.699325523577, 8679.262262707489, 11756.294622322688, 11928.940355298168, 11996.58011969577, 12005.099617528545, 12282.692932676755], "value": 10076.70166646628, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.3391139208884, 1522.6269563956832, 1567.4357231396698, 1751.430784994877, 1929.0740444586606, 8603.91396086039, 8978.685745998891, 8980.01219865815, 9191.080891910806, 9213.815723685522, 8952.060033696449, 9169.633214671405, 9170.587323570438, 9179.77050573736, 9185.862212066824, 2080.3862860456125, 2154.7522034966023, 2158.9244376446827, 2345.155743932183, 2385.594448943684, 2396.4128788446856, 2471.3821544613847, 2472.134752836511, 2481.9875900620473, 2487.9751202488014, 2370.1822871109525, 2389.390705370128, 2390.3307074019303, 2395.9760402396014, 2430.902763889442, 1935.5548223908438, 1999.7700264469481, 2383.1063351243283, 2419.6491508731224, 2419.7822196002357, 2074.0, 2273.6589511573397, 2343.6718859359703, 2353.341064501942, 2371.0515793682493, 8961.327900407496, 8973.730788076367, 9039.141281578239, 9047.728568142966, 9049.185573298435, 5297.788088476456, 5417.756200970965, 5441.075017247077, 5442.809501667462, 5443.455654434564, 3485.163560745428, 3515.033365824393, 3528.0, 3545.4859045438266, 3557.918617608702, 1157.2651366382324, 1171.37916904041, 1190.9761804763857, 1212.872648371917, 1216.799228127358, 13071.41178646963, 13086.149400289047, 13098.214107153506, 13111.60665180046, 13129.409176587076, 4658.532562242883, 4735.905281894412, 4772.928406073912, 4791.233402655558, 4817.975910120427, 10812.729681758019, 10814.080803131665, 10859.619913302997, 10865.402402867918, 10879.782404351869, 5432.483914028161, 5438.2386465894015, 5480.7533660985355, 5494.2857428534835, 5509.531689806411, 4447.510773814878, 4827.25177597473, 4885.853424397274, 4949.7030178189425, 4990.975045124824, 4032.838686452568, 4090.141070375195, 4109.815058322323, 4185.079282557833, 4188.204241194163, 1283.8074288856747, 1296.3842174966815, 1302.7459645369063, 1306.0857399820163, 1308.188922867828, 963.6386355116831, 1027.7173777211178, 1046.6441409920662, 1049.1134990932649, 1049.2235745548392, 5954.910676339859, 6026.638401695912, 6111.022236442148, 6122.571420000646, 6314.55798094129, 495.89834084012693, 496.1738705056113, 498.7456397237435, 500.3570412020609, 505.2169137836339, 4231.0, 4286.914261714748, 4296.828126874952, 4320.675949303814, 4409.757463339483, 10866.67399978002, 11264.042556382636, 11349.432528373549, 11363.090952723762, 11373.431328433546, 10602.204834637432, 10861.402622855661, 10951.780964380669, 11144.944275278573, 11290.435478226056, 8316.792080198044, 8361.790955226046, 8383.371247156365, 8507.787305317293, 8607.784805379915, 6136.417040381157, 6137.877242455215, 6163.7842675506145, 6207.503399728102, 6211.2857021443115, 10249.180065594883, 10253.948730256301, 10328.483575821328, 10456.268061235945, 10464.0, 10326.793464130826, 10401.739956501147, 10579.682609521727, 10671.253012289066, 10698.251122421356, 7390.224026477143, 7657.119431265253, 7659.463837531319, 7822.765317040498, 7952.0, 8472.83054338922, 8703.651853926025, 8714.825703486022, 8919.598618062073, 8922.910770892207, 7900.525968441912, 8079.515229086273, 8106.259889487498, 8165.0, 8169.591520424074, 7878.290953814184, 7954.960225198837, 8026.036875574969, 8085.8787118192095, 8138.715144969898, 6908.067410899665, 7119.501634885508, 7266.96366518164, 7325.157606875277, 7342.449316301192, 5309.415964244007, 5378.058839702958, 5409.486098820606, 5439.0753571893665, 5454.863628409322, 5229.424763276113, 5243.449437809128, 5286.755741335155, 5336.786528538815, 5578.832635020955], "value": 4694.081958435908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.9898003059908, 344.39214785902885, 349.56828317028476, 351.917299434633, 353.48920809430354, 5346.3584369875625, 5399.838004859856, 5415.9458405415935, 5417.160340147283, 5417.26866872972, 5289.153735402337, 5305.7877684892555, 5321.228421878825, 5372.749486560623, 5377.381601115872, 3909.866138819739, 3963.8810835674904, 3977.9204415911663, 4033.0, 4058.533268674096, 4182.996080940583, 4183.686223533232, 4189.549113768449, 4198.244316023115, 4201.8654963159925, 4123.917521649565, 4129.504459464853, 4144.357624568197, 4152.875413737593, 4196.853110141131, 3944.23087497938, 4085.59144085592, 4186.476690413699, 4186.497620285555, 4222.303319952204, 3716.0709822544363, 3741.289155060557, 3746.756460830037, 3754.3242216401036, 3763.209725957552, 5154.355705536808, 5155.484451554852, 5164.896702065976, 5184.988927159206, 5202.569293444333, 4689.859304220879, 4733.976330118362, 4756.167670657621, 4775.928361074587, 4787.976060119712, 5529.645236916941, 5536.640118392332, 5553.528679737478, 5554.805581804618, 5557.72211389433, 4105.0, 4106.507219133723, 4106.650934670557, 4107.096438783463, 4118.258713431581, 5687.829365119053, 5751.7699292028265, 5770.7403166857175, 5823.359430462688, 5836.0, 4174.728642638219, 4179.644730197906, 4191.3293872980175, 4204.516480604709, 4206.705530612828, 4214.220369231702, 4234.957650423518, 4237.131388065439, 4250.617444430016, 4252.4046633471335, 4665.0, 4800.615950723934, 4823.421189457287, 4830.903381932412, 4840.733759643183, 5578.912112138175, 5609.074502707089, 5615.8876822464135, 5622.875424915033, 5630.690312032878, 1438.2377340009812, 1459.4381163252224, 1462.5466105507326, 1464.8022516960286, 1479.1494890437825, 919.2921450483091, 955.8661787349638, 963.6557002980802, 965.7730433348157, 980.1815484070738, 4754.881127971828, 4789.492084103309, 4816.759162041884, 4860.343853579792, 4899.510048995072, 5023.447420783711, 5033.3205017322925, 5038.521340472649, 5075.8223462179385, 5092.2361645753435, 4589.10512450069, 4802.199175309259, 4844.539768721966, 4845.854624361275, 4873.878153046202, 2727.236373815335, 2729.0, 2733.221032005869, 2777.8888844445996, 2780.7636350910398, 5290.629655924048, 5294.0, 5360.812371566976, 5370.597205209547, 5411.864703382368, 4853.174960256694, 5155.536001759785, 5160.716160611202, 5190.78171533644, 5556.527695145877, 5798.9130163047585, 5880.441358070976, 5986.862496125722, 6031.42701443371, 6041.75832966694, 5780.797672081457, 5789.623674461147, 5797.43469263305, 5823.679697616672, 5825.359210486928, 5357.223202635674, 5674.2623458950075, 5689.203511508475, 5744.827655170351, 5767.961766882002, 5977.043673012297, 6052.757889684363, 6056.939430605814, 6060.818175454743, 6072.423119803611, 4186.958130418657, 4196.2656535105625, 4199.223143718422, 4200.4539409876525, 4201.369794530845, 5529.368836193413, 5707.145177817309, 5733.885322293614, 5784.652920824764, 5796.362400136066, 5582.023145949362, 5615.922108819763, 5629.211910332639, 5639.520640745583, 5647.1529270609735, 5307.4692530746615, 5558.0, 5598.880223955144, 5621.381648018633, 5633.0, 5563.638363506521, 5574.303212098488, 5602.52378547828, 5602.795398989237, 5605.887882242251, 5718.51392631631, 5730.223630674504, 5730.596003979052, 5759.51044161251, 5762.818622182526, 5416.322959630046, 5477.780888764405, 5550.222968784293, 5570.2480165177185, 5602.915956260741], "value": 4219.219077707265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.5247841381513, 807.4630370803415, 823.7569916874521, 880.2649787427498, 896.7489103051148, 13756.0, 14364.78452823206, 14364.784528232085, 14371.35328910199, 14371.425142994281, 13700.177989320648, 13746.175229486213, 13759.58721238362, 13759.931200343985, 13770.0, 682.2699711308901, 799.6161842315692, 837.1293854391425, 837.4221786967004, 839.529863276564, 838.5304229631396, 844.5861527851339, 846.6444093480753, 846.6909578004046, 847.4322204123253, 892.8526793079134, 895.400081945095, 896.6413434626137, 896.753392816974, 896.9327300452492, 717.8815495443246, 870.0907551608552, 896.1844721303647, 896.4890012692767, 897.3808072430003, 640.3020707428878, 641.1888960465016, 649.6232185332495, 650.2911826109516, 650.3756393861851, 14486.710265794625, 14494.057886237466, 14532.0, 14580.781288280685, 14582.854171458363, 14293.428262869473, 14513.492027778977, 14517.70964580713, 14535.418583256656, 14567.635809104751, 11123.0, 11506.654800356002, 11717.355545444998, 11921.761564768656, 12548.1216314858, 4384.430024096879, 4500.797464114123, 4510.842120525798, 4521.502634710179, 4529.071540334224, 54039.43213744056, 54969.72515137439, 55747.0, 56379.0, 56459.71770141164, 10970.957759012881, 11268.887311126948, 11504.712382190512, 11723.648290551297, 11795.764084718257, 19924.800751992585, 20002.69995950062, 20026.99865006744, 20034.69947950782, 20073.498162546053, 8311.833763324821, 8473.195046470575, 8556.10160933093, 8576.785580360542, 8895.332850036144, 4836.419629644395, 4847.2729090635985, 4873.707577545288, 4889.217725163957, 5049.0210775642545, 3370.5112758649857, 3468.0462872710204, 3475.4439289713523, 3476.200473891019, 3618.565772107364, 639.2744235292976, 686.0, 695.3776370148789, 698.1133959870936, 722.0901663903414, 648.2852854200707, 661.3386613386614, 692.1452006771633, 696.467202590019, 739.663453128825, 9046.321525885585, 9520.666776662783, 9541.856872146924, 9600.087991640783, 9642.228621710246, 709.095902724031, 731.5683746589538, 736.8832312082417, 740.1784019738086, 762.1768490030754, 4443.977780111079, 4473.530279320689, 4518.548145185521, 4704.88237794051, 4715.4577223619035, 53242.0, 54188.72905635447, 54688.726556366964, 63868.0, 69192.6540367295, 54094.0, 54273.0, 54684.453155467934, 55167.0, 55508.0, 50095.24857127146, 50707.23939140916, 50735.0, 51447.48552514427, 52131.73934130381, 8639.784005399915, 8895.243904268209, 9020.0, 9112.190003909634, 9321.627134914528, 34484.310313794085, 34650.30699386049, 35157.0, 35201.47197791984, 35276.47085293671, 40745.79627101846, 43012.13975720531, 43886.34170487509, 44026.559734402246, 44950.77524612356, 11997.340146292037, 12039.819402708792, 12047.879521205026, 12073.215241009306, 12234.816477753018, 12871.099023068295, 12933.159344642569, 12935.805962910375, 13550.322483875963, 13850.792238116235, 12287.877121228674, 12450.875491244971, 12456.0, 12469.625911222678, 12561.724857864569, 12270.0, 12444.320712695122, 12598.181118227287, 12610.243385396905, 12646.43091060886, 12399.690007749878, 12789.42447589842, 12871.742565148457, 12940.8058879115, 12998.285094319903, 9806.509674516388, 9842.60629574809, 9924.851127232952, 9938.214411352204, 10081.899181008095, 9776.706698799047, 10164.796704066026, 10335.896641033794, 10340.741481463023, 10350.430726310125], "value": 6853.261546986873, "unit": "score"}], "name": "dromaeo_css", "value": 7244.191034338429, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 110.0, 109.0, 108.0, 110.0, 109.0, 109.0, 109.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 153.0, 209.0, 126.0, 173.0, 156.0, 125.0, 189.0, 124.0], "value": 153.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 159.0, 160.0, 161.0, 160.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 153.0, 76.0, 74.0, 77.0, 75.0, 132.0, 76.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 153.0, 92.0, 91.0, 92.0, 128.0, 93.0, 92.0, 139.0], "value": 106.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 147.0, 147.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 56.0, 57.0, 56.0, 57.0, 109.0, 56.0, 56.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 60.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 78.0, 79.0, 86.0, 80.0, 81.0, 81.0, 82.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 128.0, 124.0, 124.0, 121.0, 123.0, 123.0, 117.0, 203.0, 128.0], "value": 132.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 165.0, 166.0, 168.0, 168.0, 168.0, 167.0, 167.0, 166.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 67.0, 64.0, 64.0, 64.0, 63.0, 64.0, 64.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1541.0000000000002, "alertThreshold": 2.0}]} 20:37:28 INFO - Return code: 0 20:37:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:37:28 ERROR - # TBPL SUCCESS # 20:37:28 INFO - Running post-action listener: _resource_record_post_action 20:37:28 INFO - ##### 20:37:28 INFO - ##### Finished run-tests step (success) 20:37:28 INFO - ##### 20:37:28 INFO - Running post-run listener: _resource_record_post_run 20:37:29 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 7991296; Write bytes: 490455040; Read time: 3736; Write time: 1269008 20:37:29 INFO - TinderboxPrint: CPU usage
13.2% 20:37:29 INFO - TinderboxPrint: I/O read bytes / time
7,991,296 / 3,736 20:37:29 INFO - TinderboxPrint: I/O write bytes / time
490,455,040 / 1,269,008 20:37:29 INFO - TinderboxPrint: CPU idle
8,332.8 (86.8%) 20:37:29 INFO - TinderboxPrint: CPU user
1,206.0 (12.6%) 20:37:29 INFO - TinderboxPrint: Swap in / out
0 / 0 20:37:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 24576; Write bytes: 74100736; Read time: 388; Write time: 75724 20:37:29 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 7966720; Write bytes: 406700032; Read time: 3348; Write time: 1191760 20:37:29 INFO - Running post-run listener: _upload_blobber_files 20:37:29 INFO - Blob upload gear active. 20:37:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:37:29 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 20:37:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:37:29 INFO - (blobuploader) - INFO - Open directory for files ... 20:37:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:37:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:37:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:30 INFO - (blobuploader) - INFO - Done attempting. 20:37:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:37:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:37:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:31 INFO - (blobuploader) - INFO - Done attempting. 20:37:31 INFO - (blobuploader) - INFO - Iteration through files over. 20:37:31 INFO - Return code: 0 20:37:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:37:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:37:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d0865425fb04685ab84b0b643b67cc269c52294a5aa4575a98332f109b2e526e1e8f29924b5c570d2881fa83cf6a746448582ff8c0bbc28358425630f4eb9b57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25a165f1be4b9e4c861dcca83eef749cab37a79ae2355d3f088fc2a59ed931d81d88f547a358ba2f42f212e57ecb110fd8211520f60824d267affeacae8c01d1"} 20:37:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:37:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:37:31 INFO - Contents: 20:37:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d0865425fb04685ab84b0b643b67cc269c52294a5aa4575a98332f109b2e526e1e8f29924b5c570d2881fa83cf6a746448582ff8c0bbc28358425630f4eb9b57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25a165f1be4b9e4c861dcca83eef749cab37a79ae2355d3f088fc2a59ed931d81d88f547a358ba2f42f212e57ecb110fd8211520f60824d267affeacae8c01d1"} 20:37:31 INFO - Running post-run listener: copy_logs_to_upload_dir 20:37:31 INFO - Copying logs to upload dir... 20:37:31 INFO - mkdir: /builds/slave/test/build/upload/logs 20:37:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.633309 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-12 20:37:31.422494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:31.425838) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d0865425fb04685ab84b0b643b67cc269c52294a5aa4575a98332f109b2e526e1e8f29924b5c570d2881fa83cf6a746448582ff8c0bbc28358425630f4eb9b57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25a165f1be4b9e4c861dcca83eef749cab37a79ae2355d3f088fc2a59ed931d81d88f547a358ba2f42f212e57ecb110fd8211520f60824d267affeacae8c01d1"} build_url:https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011035 build_url: 'https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d0865425fb04685ab84b0b643b67cc269c52294a5aa4575a98332f109b2e526e1e8f29924b5c570d2881fa83cf6a746448582ff8c0bbc28358425630f4eb9b57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25a165f1be4b9e4c861dcca83eef749cab37a79ae2355d3f088fc2a59ed931d81d88f547a358ba2f42f212e57ecb110fd8211520f60824d267affeacae8c01d1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:31.449973) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:31.450268) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463107076.935744-57860128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004381 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:31.533245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 20:37:31.533563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 20:37:31.533891) ========= ========= Total master_lag: 0.20 =========