builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1465178499.06 results: success (0) buildid: 20160605163003 builduid: 40dec010446b4dbe8612b8205283e671 revision: e54065dbe41dc0192f5d1710ad1ba5855f573545 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.058965) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.059396) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.059705) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.110576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.110919) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-05 19:01:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-06-05 19:01:39 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092601 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.220287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.220610) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015575 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.270908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.271292) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e54065dbe41dc0192f5d1710ad1ba5855f573545 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e54065dbe41dc0192f5d1710ad1ba5855f573545 --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=2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-05 19:01:39,647 truncating revision to first 12 chars 2016-06-05 19:01:39,647 Setting DEBUG logging. 2016-06-05 19:01:39,647 attempt 1/10 2016-06-05 19:01:39,647 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/e54065dbe41d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-05 19:01:39,966 unpacking tar archive at: mozilla-inbound-e54065dbe41d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657312 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.942973) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.943320) ========= script_repo_revision: e54065dbe41dc0192f5d1710ad1ba5855f573545 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.943801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.944115) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 19:01:39.953352) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 31 secs) (at 2016-06-05 19:01:39.953706) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:01:40 INFO - MultiFileLogger online at 20160605 19:01:40 in /builds/slave/test 19:01:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 19:01:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:40 INFO - {'append_to_log': False, 19:01:40 INFO - 'base_work_dir': '/builds/slave/test', 19:01:40 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:01:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:40 INFO - 'branch': 'Mozilla-Inbound', 19:01:40 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:40 INFO - 'config_files': ('talos/linux_config.py',), 19:01:40 INFO - 'default_actions': ('clobber', 19:01:40 INFO - 'read-buildbot-config', 19:01:40 INFO - 'download-and-extract', 19:01:40 INFO - 'populate-webroot', 19:01:40 INFO - 'create-virtualenv', 19:01:40 INFO - 'install', 19:01:40 INFO - 'run-tests'), 19:01:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:40 INFO - 'download_minidump_stackwalk': True, 19:01:40 INFO - 'download_symbols': 'ondemand', 19:01:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:40 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:40 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:40 INFO - 'installer_path': 'installer.exe', 19:01:40 INFO - 'log_level': 'info', 19:01:40 INFO - 'log_name': 'talos', 19:01:40 INFO - 'log_to_console': True, 19:01:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:01:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:01:40 INFO - 'opt_config_files': (), 19:01:40 INFO - 'pip_index': False, 19:01:40 INFO - 'sps_profile': False, 19:01:40 INFO - 'sps_profile_interval': 0, 19:01:40 INFO - 'suite': 'dromaeojs', 19:01:40 INFO - 'system_bits': '32', 19:01:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:40 INFO - 'title': 'talos-linux64-ix-012', 19:01:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:40 INFO - 'use_talos_json': True, 19:01:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:40 INFO - 'work_dir': 'build'} 19:01:40 INFO - ##### 19:01:40 INFO - ##### Running clobber step. 19:01:40 INFO - ##### 19:01:40 INFO - Running pre-action listener: _resource_record_pre_action 19:01:40 INFO - Running main action method: clobber 19:01:40 INFO - rmtree: /builds/slave/test/build 19:01:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:40 INFO - Running post-action listener: _resource_record_post_action 19:01:40 INFO - ##### 19:01:40 INFO - ##### Finished clobber step (success) 19:01:40 INFO - ##### 19:01:40 INFO - ##### 19:01:40 INFO - ##### Running read-buildbot-config step. 19:01:40 INFO - ##### 19:01:40 INFO - Running pre-action listener: _resource_record_pre_action 19:01:40 INFO - Running main action method: read_buildbot_config 19:01:40 INFO - Using buildbot properties: 19:01:40 INFO - { 19:01:40 INFO - "project": "", 19:01:40 INFO - "product": "firefox", 19:01:40 INFO - "script_repo_revision": "production", 19:01:40 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:01:40 INFO - "repository": "", 19:01:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:01:40 INFO - "buildid": "20160605163003", 19:01:40 INFO - "pgo_build": "True", 19:01:40 INFO - "basedir": "/builds/slave/test", 19:01:40 INFO - "buildnumber": 1895, 19:01:40 INFO - "slavename": "talos-linux64-ix-012", 19:01:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:01:40 INFO - "platform": "ubuntu64_hw", 19:01:40 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:01:40 INFO - "branch": "mozilla-inbound", 19:01:40 INFO - "repo_path": "integration/mozilla-inbound", 19:01:40 INFO - "revision": "e54065dbe41dc0192f5d1710ad1ba5855f573545", 19:01:40 INFO - "stage_platform": "linux64-pgo", 19:01:40 INFO - "builduid": "40dec010446b4dbe8612b8205283e671", 19:01:40 INFO - "slavebuilddir": "test" 19:01:40 INFO - } 19:01:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:01:40 INFO - Running post-action listener: _resource_record_post_action 19:01:40 INFO - ##### 19:01:40 INFO - ##### Finished read-buildbot-config step (success) 19:01:40 INFO - ##### 19:01:40 INFO - ##### 19:01:40 INFO - ##### Running download-and-extract step. 19:01:40 INFO - ##### 19:01:40 INFO - Running pre-action listener: _resource_record_pre_action 19:01:40 INFO - Running main action method: download_and_extract 19:01:40 INFO - mkdir: /builds/slave/test/build/tests 19:01:40 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')]} 19:01:40 INFO - https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:01:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:01:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:01:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:01:41 INFO - Downloaded 1361 bytes. 19:01:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:01:41 INFO - Using the following test package requirements: 19:01:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:01:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:01:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:01:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'jsshell-linux-x86_64.zip'], 19:01:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:01:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:01:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:01:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:01:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:01:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:01:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:01:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:01: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')]} 19:01:41 INFO - https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:01:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:01:46 INFO - Downloaded 24443848 bytes. 19:01:46 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'] 19:01:46 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 19:01:47 INFO - Return code: 0 19:01:47 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 19:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:47 INFO - https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:01:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:01:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:01:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:03:32 INFO - Downloaded 24443848 bytes. 19:03:32 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'] 19:03:32 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 19:03:33 INFO - Return code: 0 19:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:33 INFO - https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:03:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:03:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:03:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:03:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:03:35 INFO - Downloaded 10833369 bytes. 19:03:35 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'] 19:03:35 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 19:03:35 INFO - Return code: 0 19:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:35 INFO - https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:03:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:03:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:03:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:03:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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 19:03:48 INFO - Downloaded 59192519 bytes. 19:03:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:03:48 INFO - mkdir: /builds/slave/test/properties 19:03:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:48 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:48 INFO - Contents: 19:03:48 INFO - build_url:https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:03:48 INFO - Running post-action listener: _resource_record_post_action 19:03:48 INFO - Running post-action listener: set_extra_try_arguments 19:03:48 INFO - ##### 19:03:48 INFO - ##### Finished download-and-extract step (success) 19:03:48 INFO - ##### 19:03:48 INFO - ##### 19:03:48 INFO - ##### Running populate-webroot step. 19:03:48 INFO - ##### 19:03:48 INFO - Running pre-action listener: _resource_record_pre_action 19:03:48 INFO - Running main action method: populate_webroot 19:03:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:03:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:03:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:03:49 INFO - '--tppagecycles', 19:03:49 INFO - '7'], 19:03:49 INFO - 'tests': ['tsvgm']}}, 19:03:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:03:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:03:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['damp', 'tps']}, 19:03:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'tests': ['damp', 'tps']}, 19:03:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['dromaeo_dom']}, 19:03:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['a11yr', 19:03:49 INFO - 'ts_paint', 19:03:49 INFO - 'tpaint', 19:03:49 INFO - 'sessionrestore', 19:03:49 INFO - 'sessionrestore_no_auto_restore', 19:03:49 INFO - 'tabpaint']}, 19:03:49 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:49 INFO - 'ts_paint', 19:03:49 INFO - 'tpaint', 19:03:49 INFO - 'sessionrestore', 19:03:49 INFO - 'sessionrestore_no_auto_restore', 19:03:49 INFO - 'tabpaint']}, 19:03:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:03:49 INFO - 'ts_paint', 19:03:49 INFO - 'tpaint', 19:03:49 INFO - 'sessionrestore', 19:03:49 INFO - 'sessionrestore_no_auto_restore', 19:03:49 INFO - 'tabpaint']}, 19:03:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:03:49 INFO - 'ts_paint', 19:03:49 INFO - 'tpaint', 19:03:49 INFO - 'sessionrestore', 19:03:49 INFO - 'sessionrestore_no_auto_restore', 19:03:49 INFO - 'tabpaint']}, 19:03:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['a11yr', 19:03:49 INFO - 'ts_paint', 19:03:49 INFO - 'tpaint', 19:03:49 INFO - 'sessionrestore', 19:03:49 INFO - 'sessionrestore_no_auto_restore', 19:03:49 INFO - 'tabpaint']}, 19:03:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['a11yr', 19:03:49 INFO - 'ts_paint', 19:03:49 INFO - 'tpaint', 19:03:49 INFO - 'sessionrestore', 19:03:49 INFO - 'sessionrestore_no_auto_restore', 19:03:49 INFO - 'tabpaint']}, 19:03:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['tsvgx', 19:03:49 INFO - 'tsvgr_opacity', 19:03:49 INFO - 'tart', 19:03:49 INFO - 'tscrollx', 19:03:49 INFO - 'cart']}, 19:03:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:49 INFO - 'tsvgr_opacity', 19:03:49 INFO - 'tart', 19:03:49 INFO - 'tscrollx', 19:03:49 INFO - 'cart']}, 19:03:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'talos_options': ['--disable-e10s'], 19:03:49 INFO - 'tests': ['tp5o']}, 19:03:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'tests': ['tp5o']}, 19:03:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'talos_options': ['--disable-e10s', 19:03:49 INFO - '--xperf_path', 19:03:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:49 INFO - 'tests': ['tp5n']}, 19:03:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:49 INFO - 'talos_options': ['--xperf_path', 19:03:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:49 INFO - 'tests': ['tp5n']}}, 19:03:49 INFO - 'talos.zip': {'path': '', 19:03:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:03:49 INFO - Running post-action listener: _resource_record_post_action 19:03:49 INFO - ##### 19:03:49 INFO - ##### Finished populate-webroot step (success) 19:03:49 INFO - ##### 19:03:49 INFO - ##### 19:03:49 INFO - ##### Running create-virtualenv step. 19:03:49 INFO - ##### 19:03:49 INFO - Running pre-action listener: _resource_record_pre_action 19:03:49 INFO - Running main action method: create_virtualenv 19:03:49 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:49 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 19:03:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:49 INFO - Using real prefix '/usr' 19:03:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:49 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:51 INFO - Installing pip.................done. 19:03:51 INFO - Return code: 0 19:03:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:03:51 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')]} 19:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:51 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')]} 19:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:51 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:03:51 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 19:03:51 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 19:03:51 INFO - Using env: {'DISPLAY': ':0', 19:03:51 INFO - 'HOME': '/home/cltbld', 19:03:51 INFO - 'LANG': 'en_US.UTF-8', 19:03:51 INFO - 'LANGUAGE': 'en_US:en', 19:03:51 INFO - 'LOGNAME': 'cltbld', 19:03:51 INFO - 'MAIL': '/var/mail/cltbld', 19:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:51 INFO - 'MOZ_NO_REMOTE': '1', 19:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:51 INFO - 'NO_EM_RESTART': '1', 19:03:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:51 INFO - 'PWD': '/builds/slave/test', 19:03:51 INFO - 'SHELL': '/bin/bash', 19:03:51 INFO - 'SHLVL': '1', 19:03:51 INFO - 'TERM': 'linux', 19:03:51 INFO - 'TMOUT': '86400', 19:03:51 INFO - 'USER': 'cltbld', 19:03:51 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:51 INFO - '_': '/tools/buildbot/bin/python'} 19:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:51 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 19:03:51 INFO - Cleaning up... 19:03:51 INFO - Return code: 0 19:03:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:03:51 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')]} 19:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:51 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')]} 19:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:03:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:03:51 INFO - Using env: {'DISPLAY': ':0', 19:03:51 INFO - 'HOME': '/home/cltbld', 19:03:51 INFO - 'LANG': 'en_US.UTF-8', 19:03:51 INFO - 'LANGUAGE': 'en_US:en', 19:03:51 INFO - 'LOGNAME': 'cltbld', 19:03:51 INFO - 'MAIL': '/var/mail/cltbld', 19:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:51 INFO - 'MOZ_NO_REMOTE': '1', 19:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:51 INFO - 'NO_EM_RESTART': '1', 19:03:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:51 INFO - 'PWD': '/builds/slave/test', 19:03:51 INFO - 'SHELL': '/bin/bash', 19:03:51 INFO - 'SHLVL': '1', 19:03:51 INFO - 'TERM': 'linux', 19:03:51 INFO - 'TMOUT': '86400', 19:03:51 INFO - 'USER': 'cltbld', 19:03:51 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:51 INFO - '_': '/tools/buildbot/bin/python'} 19:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:51 INFO - Downloading/unpacking psutil>=3.1.1 19:03:51 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 19:03:51 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 19:03:51 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 19:03:51 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 19:03:51 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 19:03:51 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 19:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:03:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:53 INFO - Installing collected packages: psutil 19:03:53 INFO - Running setup.py install for psutil 19:03:54 INFO - building 'psutil._psutil_linux' extension 19:03:54 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 19:03:54 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 19:03:54 INFO - building 'psutil._psutil_posix' extension 19:03:54 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 19:03:54 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 19:03:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:54 INFO - Successfully installed psutil 19:03:54 INFO - Cleaning up... 19:03:54 INFO - Return code: 0 19:03:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:03:54 INFO - Using env: {'DISPLAY': ':0', 19:03:54 INFO - 'HOME': '/home/cltbld', 19:03:54 INFO - 'LANG': 'en_US.UTF-8', 19:03:54 INFO - 'LANGUAGE': 'en_US:en', 19:03:54 INFO - 'LOGNAME': 'cltbld', 19:03:54 INFO - 'MAIL': '/var/mail/cltbld', 19:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:54 INFO - 'MOZ_NO_REMOTE': '1', 19:03:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:54 INFO - 'NO_EM_RESTART': '1', 19:03:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:54 INFO - 'PWD': '/builds/slave/test', 19:03:54 INFO - 'SHELL': '/bin/bash', 19:03:54 INFO - 'SHLVL': '1', 19:03:54 INFO - 'TERM': 'linux', 19:03:54 INFO - 'TMOUT': '86400', 19:03:54 INFO - 'USER': 'cltbld', 19:03:54 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:54 INFO - '_': '/tools/buildbot/bin/python'} 19:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:03:54 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 19:03:54 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 19:03:54 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 19:03:54 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 19:03:54 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 19:03:54 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 19:03:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:03:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:03:56 INFO - Installing collected packages: mozsystemmonitor 19:03:56 INFO - Running setup.py install for mozsystemmonitor 19:03:56 INFO - Successfully installed mozsystemmonitor 19:03:56 INFO - Cleaning up... 19:03:56 INFO - Return code: 0 19:03:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:03:56 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')]} 19:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:56 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')]} 19:03:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:03:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:03:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:03:56 INFO - Using env: {'DISPLAY': ':0', 19:03:56 INFO - 'HOME': '/home/cltbld', 19:03:56 INFO - 'LANG': 'en_US.UTF-8', 19:03:56 INFO - 'LANGUAGE': 'en_US:en', 19:03:56 INFO - 'LOGNAME': 'cltbld', 19:03:56 INFO - 'MAIL': '/var/mail/cltbld', 19:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:56 INFO - 'MOZ_NO_REMOTE': '1', 19:03:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:56 INFO - 'NO_EM_RESTART': '1', 19:03:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:56 INFO - 'PWD': '/builds/slave/test', 19:03:56 INFO - 'SHELL': '/bin/bash', 19:03:56 INFO - 'SHLVL': '1', 19:03:56 INFO - 'TERM': 'linux', 19:03:56 INFO - 'TMOUT': '86400', 19:03:56 INFO - 'USER': 'cltbld', 19:03:56 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:56 INFO - '_': '/tools/buildbot/bin/python'} 19:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:57 INFO - Downloading/unpacking jsonschema==2.5.1 19:03:57 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 19:03:57 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 19:03:57 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 19:03:57 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 19:03:57 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 19:03:57 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 19:03:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:03:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:59 INFO - Downloading functools32-3.2.3-2.tar.gz 19:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:03:59 INFO - warning: no files found matching '*.txt' 19:03:59 INFO - no previously-included directories found matching 'build' 19:03:59 INFO - no previously-included directories found matching 'dist' 19:03:59 INFO - no previously-included directories found matching '.git*' 19:03:59 INFO - Installing collected packages: jsonschema, functools32 19:03:59 INFO - Running setup.py install for functools32 19:03:59 INFO - warning: no files found matching '*.txt' 19:03:59 INFO - no previously-included directories found matching 'build' 19:03:59 INFO - no previously-included directories found matching 'dist' 19:03:59 INFO - no previously-included directories found matching '.git*' 19:03:59 INFO - Successfully installed jsonschema functools32 19:03:59 INFO - Cleaning up... 19:03:59 INFO - Return code: 0 19:03:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:03:59 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')]} 19:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:59 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')]} 19:03:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:59 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:03:59 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 19:03:59 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 19:03:59 INFO - Using env: {'DISPLAY': ':0', 19:03:59 INFO - 'HOME': '/home/cltbld', 19:03:59 INFO - 'LANG': 'en_US.UTF-8', 19:03:59 INFO - 'LANGUAGE': 'en_US:en', 19:03:59 INFO - 'LOGNAME': 'cltbld', 19:03:59 INFO - 'MAIL': '/var/mail/cltbld', 19:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:59 INFO - 'MOZ_NO_REMOTE': '1', 19:03:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:59 INFO - 'NO_EM_RESTART': '1', 19:03:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:59 INFO - 'PWD': '/builds/slave/test', 19:03:59 INFO - 'SHELL': '/bin/bash', 19:03:59 INFO - 'SHLVL': '1', 19:03:59 INFO - 'TERM': 'linux', 19:03:59 INFO - 'TMOUT': '86400', 19:03:59 INFO - 'USER': 'cltbld', 19:03:59 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:59 INFO - '_': '/tools/buildbot/bin/python'} 19:03:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:59 INFO - Downloading/unpacking blobuploader==1.2.4 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 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 19:04:01 INFO - Downloading blobuploader-1.2.4.tar.gz 19:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:04:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:04:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 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 19:04:01 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 19:04:02 INFO - Downloading docopt-0.6.1.tar.gz 19:04:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:04:02 INFO - Installing collected packages: blobuploader, requests, docopt 19:04:02 INFO - Running setup.py install for blobuploader 19:04:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:04:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:04:02 INFO - Running setup.py install for requests 19:04:02 INFO - Running setup.py install for docopt 19:04:02 INFO - Successfully installed blobuploader requests docopt 19:04:02 INFO - Cleaning up... 19:04:02 INFO - Return code: 0 19:04:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:02 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:04:02 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 19:04:02 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 19:04:02 INFO - Using env: {'DISPLAY': ':0', 19:04:02 INFO - 'HOME': '/home/cltbld', 19:04:02 INFO - 'LANG': 'en_US.UTF-8', 19:04:02 INFO - 'LANGUAGE': 'en_US:en', 19:04:02 INFO - 'LOGNAME': 'cltbld', 19:04:02 INFO - 'MAIL': '/var/mail/cltbld', 19:04:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:02 INFO - 'MOZ_NO_REMOTE': '1', 19:04:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:02 INFO - 'NO_EM_RESTART': '1', 19:04:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:02 INFO - 'PWD': '/builds/slave/test', 19:04:02 INFO - 'SHELL': '/bin/bash', 19:04:02 INFO - 'SHLVL': '1', 19:04:02 INFO - 'TERM': 'linux', 19:04:02 INFO - 'TMOUT': '86400', 19:04:02 INFO - 'USER': 'cltbld', 19:04:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:04:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:02 INFO - '_': '/tools/buildbot/bin/python'} 19:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:03 INFO - Running setup.py (path:/tmp/pip-9FZ9UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:03 INFO - Running setup.py (path:/tmp/pip-h2_TuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:03 INFO - Running setup.py (path:/tmp/pip-Gg3vvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:03 INFO - Running setup.py (path:/tmp/pip-73o7Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:03 INFO - Running setup.py (path:/tmp/pip-6qOmKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:03 INFO - Running setup.py (path:/tmp/pip-uxdn06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:03 INFO - Running setup.py (path:/tmp/pip-roJpNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:03 INFO - Running setup.py (path:/tmp/pip-dCqovC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:03 INFO - Running setup.py (path:/tmp/pip-9sas8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:03 INFO - Running setup.py (path:/tmp/pip-_XzuQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:03 INFO - Running setup.py (path:/tmp/pip-UAgd32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:03 INFO - Running setup.py (path:/tmp/pip-CRWlke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:03 INFO - Running setup.py (path:/tmp/pip-hRYXQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:03 INFO - Running setup.py (path:/tmp/pip-7f9AfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:04 INFO - Running setup.py (path:/tmp/pip-NtsY0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:04 INFO - Running setup.py (path:/tmp/pip-pXMypH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:04 INFO - Running setup.py (path:/tmp/pip-7Kc7Rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:04:04 INFO - Running setup.py install for manifestparser 19:04:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozcrash 19:04:04 INFO - Running setup.py install for mozdebug 19:04:04 INFO - Running setup.py install for mozdevice 19:04:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozfile 19:04:04 INFO - Running setup.py install for mozhttpd 19:04:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozinfo 19:04:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozInstall 19:04:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Running setup.py install for mozleak 19:04:05 INFO - Running setup.py install for mozlog 19:04:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Running setup.py install for moznetwork 19:04:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Running setup.py install for mozprocess 19:04:05 INFO - Running setup.py install for mozprofile 19:04:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Running setup.py install for mozrunner 19:04:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Running setup.py install for mozscreenshot 19:04:05 INFO - Running setup.py install for moztest 19:04:05 INFO - Running setup.py install for mozversion 19:04:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:04:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:04:06 INFO - Cleaning up... 19:04:06 INFO - Return code: 0 19:04:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:06 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:04:06 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 19:04:06 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 19:04:06 INFO - Using env: {'DISPLAY': ':0', 19:04:06 INFO - 'HOME': '/home/cltbld', 19:04:06 INFO - 'LANG': 'en_US.UTF-8', 19:04:06 INFO - 'LANGUAGE': 'en_US:en', 19:04:06 INFO - 'LOGNAME': 'cltbld', 19:04:06 INFO - 'MAIL': '/var/mail/cltbld', 19:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:06 INFO - 'MOZ_NO_REMOTE': '1', 19:04:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:06 INFO - 'NO_EM_RESTART': '1', 19:04:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:06 INFO - 'PWD': '/builds/slave/test', 19:04:06 INFO - 'SHELL': '/bin/bash', 19:04:06 INFO - 'SHLVL': '1', 19:04:06 INFO - 'TERM': 'linux', 19:04:06 INFO - 'TMOUT': '86400', 19:04:06 INFO - 'USER': 'cltbld', 19:04:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:06 INFO - '_': '/tools/buildbot/bin/python'} 19:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:06 INFO - Running setup.py (path:/tmp/pip-zjjy6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:06 INFO - Running setup.py (path:/tmp/pip-PlIdAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:06 INFO - Running setup.py (path:/tmp/pip-gkEbze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:06 INFO - Running setup.py (path:/tmp/pip-WdXHIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:06 INFO - Running setup.py (path:/tmp/pip-JDqVgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:06 INFO - Running setup.py (path:/tmp/pip-YfJO0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:06 INFO - Running setup.py (path:/tmp/pip-wOcrmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:06 INFO - Running setup.py (path:/tmp/pip-vYkJiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:06 INFO - Running setup.py (path:/tmp/pip-k2vGy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:06 INFO - Running setup.py (path:/tmp/pip-UvT4f9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:06 INFO - Running setup.py (path:/tmp/pip-OGGcP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:07 INFO - Running setup.py (path:/tmp/pip-cO39LA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:07 INFO - Running setup.py (path:/tmp/pip-B64Zjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:07 INFO - Running setup.py (path:/tmp/pip-_DEuMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:07 INFO - Running setup.py (path:/tmp/pip-f5Heeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:07 INFO - Running setup.py (path:/tmp/pip-DmE5Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:07 INFO - Running setup.py (path:/tmp/pip-_AGVaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:07 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)) 19:04:07 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)) 19:04:07 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)) 19:04:07 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)) 19:04:07 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)) 19:04:07 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)) 19:04:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:04:07 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 19:04:07 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 19:04:07 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 19:04:07 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 19:04:07 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 19:04:07 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 19:04:09 INFO - Downloading blessings-1.6.tar.gz 19:04:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:04:09 INFO - Installing collected packages: blessings 19:04:09 INFO - Running setup.py install for blessings 19:04:09 INFO - Successfully installed blessings 19:04:09 INFO - Cleaning up... 19:04:09 INFO - Return code: 0 19:04:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:04:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:04:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:04:09 INFO - Reading from file tmpfile_stdout 19:04:09 INFO - Current package versions: 19:04:09 INFO - argparse == 1.2.1 19:04:09 INFO - blessings == 1.6 19:04:09 INFO - blobuploader == 1.2.4 19:04:09 INFO - docopt == 0.6.1 19:04:09 INFO - functools32 == 3.2.3-2 19:04:09 INFO - jsonschema == 2.5.1 19:04:09 INFO - manifestparser == 1.1 19:04:09 INFO - mozInstall == 1.12 19:04:09 INFO - mozcrash == 0.17 19:04:09 INFO - mozdebug == 0.1 19:04:09 INFO - mozdevice == 0.48 19:04:09 INFO - mozfile == 1.2 19:04:09 INFO - mozhttpd == 0.7 19:04:09 INFO - mozinfo == 0.9 19:04:09 INFO - mozleak == 0.1 19:04:09 INFO - mozlog == 3.2 19:04:09 INFO - moznetwork == 0.27 19:04:09 INFO - mozprocess == 0.22 19:04:09 INFO - mozprofile == 0.28 19:04:09 INFO - mozrunner == 6.11 19:04:09 INFO - mozscreenshot == 0.1 19:04:09 INFO - mozsystemmonitor == 0.3 19:04:09 INFO - moztest == 0.7 19:04:09 INFO - mozversion == 1.4 19:04:09 INFO - psutil == 3.1.1 19:04:09 INFO - requests == 1.2.3 19:04:09 INFO - wsgiref == 0.1.2 19:04:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:09 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')]} 19:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:09 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')]} 19:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:09 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:04:09 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 19:04:09 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 19:04:09 INFO - Using env: {'DISPLAY': ':0', 19:04:09 INFO - 'HOME': '/home/cltbld', 19:04:09 INFO - 'LANG': 'en_US.UTF-8', 19:04:09 INFO - 'LANGUAGE': 'en_US:en', 19:04:09 INFO - 'LOGNAME': 'cltbld', 19:04:09 INFO - 'MAIL': '/var/mail/cltbld', 19:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:09 INFO - 'MOZ_NO_REMOTE': '1', 19:04:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:09 INFO - 'NO_EM_RESTART': '1', 19:04:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:09 INFO - 'PWD': '/builds/slave/test', 19:04:09 INFO - 'SHELL': '/bin/bash', 19:04:09 INFO - 'SHLVL': '1', 19:04:09 INFO - 'TERM': 'linux', 19:04:09 INFO - 'TMOUT': '86400', 19:04:09 INFO - 'USER': 'cltbld', 19:04:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:09 INFO - '_': '/tools/buildbot/bin/python'} 19:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 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)) 19:04:10 INFO - Cleaning up... 19:04:10 INFO - Return code: 0 19:04:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:04:10 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')]} 19:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:10 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')]} 19:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:10 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:04:10 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 19:04:10 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 19:04:10 INFO - Using env: {'DISPLAY': ':0', 19:04:10 INFO - 'HOME': '/home/cltbld', 19:04:10 INFO - 'LANG': 'en_US.UTF-8', 19:04:10 INFO - 'LANGUAGE': 'en_US:en', 19:04:10 INFO - 'LOGNAME': 'cltbld', 19:04:10 INFO - 'MAIL': '/var/mail/cltbld', 19:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:10 INFO - 'MOZ_NO_REMOTE': '1', 19:04:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:10 INFO - 'NO_EM_RESTART': '1', 19:04:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:10 INFO - 'PWD': '/builds/slave/test', 19:04:10 INFO - 'SHELL': '/bin/bash', 19:04:10 INFO - 'SHLVL': '1', 19:04:10 INFO - 'TERM': 'linux', 19:04:10 INFO - 'TMOUT': '86400', 19:04:10 INFO - 'USER': 'cltbld', 19:04:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:10 INFO - '_': '/tools/buildbot/bin/python'} 19:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:04:10 INFO - Cleaning up... 19:04:10 INFO - Return code: 0 19:04:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:04:10 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')]} 19:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:10 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')]} 19:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:10 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 0x7f800c53df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', '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 19:04:10 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 19:04:10 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 19:04:10 INFO - Using env: {'DISPLAY': ':0', 19:04:10 INFO - 'HOME': '/home/cltbld', 19:04:10 INFO - 'LANG': 'en_US.UTF-8', 19:04:10 INFO - 'LANGUAGE': 'en_US:en', 19:04:10 INFO - 'LOGNAME': 'cltbld', 19:04:10 INFO - 'MAIL': '/var/mail/cltbld', 19:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:10 INFO - 'MOZ_NO_REMOTE': '1', 19:04:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:10 INFO - 'NO_EM_RESTART': '1', 19:04:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:10 INFO - 'PWD': '/builds/slave/test', 19:04:10 INFO - 'SHELL': '/bin/bash', 19:04:10 INFO - 'SHLVL': '1', 19:04:10 INFO - 'TERM': 'linux', 19:04:10 INFO - 'TMOUT': '86400', 19:04:10 INFO - 'USER': 'cltbld', 19:04:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:10 INFO - '_': '/tools/buildbot/bin/python'} 19:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:10 INFO - Downloading/unpacking flake8 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:04:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:04:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:12 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 19:04:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:04:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:04:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:04:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:04:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:04:13 INFO - no previously-included directories found matching 'docs/_build' 19:04:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:04:13 INFO - Running setup.py install for pep8 19:04:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:04:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:04:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:04:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:04:13 INFO - no previously-included directories found matching 'docs/_build' 19:04:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:04:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:04:13 INFO - Cleaning up... 19:04:13 INFO - Return code: 0 19:04:13 INFO - Running post-action listener: _resource_record_post_action 19:04:13 INFO - Running post-action listener: _start_resource_monitoring 19:04:13 INFO - Starting resource monitoring. 19:04:13 INFO - ##### 19:04:13 INFO - ##### Finished create-virtualenv step (success) 19:04:13 INFO - ##### 19:04:13 INFO - ##### 19:04:13 INFO - ##### Running install step. 19:04:13 INFO - ##### 19:04:13 INFO - Running pre-action listener: _resource_record_pre_action 19:04:13 INFO - Running main action method: install 19:04:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:04:13 INFO - Reading from file tmpfile_stdout 19:04:13 INFO - Detecting whether we're running mozinstall >=1.0... 19:04:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:04:13 INFO - Reading from file tmpfile_stdout 19:04:13 INFO - Output received: 19:04:13 INFO - Usage: mozinstall [options] installer 19:04:13 INFO - Options: 19:04:13 INFO - -h, --help show this help message and exit 19:04:13 INFO - -d DEST, --destination=DEST 19:04:13 INFO - Directory to install application into. [default: 19:04:13 INFO - "/builds/slave/test"] 19:04:13 INFO - --app=APP Application being installed. [default: firefox] 19:04:13 INFO - mkdir: /builds/slave/test/build/application 19:04:13 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'] 19:04:13 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 19:04:30 INFO - Reading from file tmpfile_stdout 19:04:30 INFO - Output received: 19:04:30 INFO - /builds/slave/test/build/application/firefox/firefox 19:04:30 INFO - Running post-action listener: _resource_record_post_action 19:04:30 INFO - ##### 19:04:30 INFO - ##### Finished install step (success) 19:04:30 INFO - ##### 19:04:30 INFO - ##### 19:04:30 INFO - ##### Running run-tests step. 19:04:30 INFO - ##### 19:04:30 INFO - Running pre-action listener: _resource_record_pre_action 19:04:30 INFO - Running main action method: run_tests 19:04:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:04:30 INFO - Python 2.7.3 19:04:30 INFO - Return code: 0 19:04:30 INFO - grabbing minidump binary from tooltool 19:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:30 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 0x2bee1c0>, '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 0x2bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:04:30 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 19:04:30 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 19:04:30 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 19:04:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:04:30 INFO - Return code: 0 19:04:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:04:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:04:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:04:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:04:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:04:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:04:31 INFO - Return code: 0 19:04:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 19:04:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 19:04:31 INFO - Using env: {'DISPLAY': ':0', 19:04:31 INFO - 'HOME': '/home/cltbld', 19:04:31 INFO - 'LANG': 'en_US.UTF-8', 19:04:31 INFO - 'LANGUAGE': 'en_US:en', 19:04:31 INFO - 'LOGNAME': 'cltbld', 19:04:31 INFO - 'MAIL': '/var/mail/cltbld', 19:04:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:31 INFO - 'MOZ_NO_REMOTE': '1', 19:04:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:31 INFO - 'NO_EM_RESTART': '1', 19:04:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:31 INFO - 'PWD': '/builds/slave/test', 19:04:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:31 INFO - 'SHELL': '/bin/bash', 19:04:31 INFO - 'SHLVL': '1', 19:04:31 INFO - 'TERM': 'linux', 19:04:31 INFO - 'TMOUT': '86400', 19:04:31 INFO - 'USER': 'cltbld', 19:04:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998', 19:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:31 INFO - '_': '/tools/buildbot/bin/python'} 19:04:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 19:04:31 INFO - mozversion application_buildid: 20160605163003 19:04:31 INFO - mozversion application_changeset: e54065dbe41dc0192f5d1710ad1ba5855f573545 19:04:31 INFO - mozversion application_display_name: Nightly 19:04:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:04:31 INFO - mozversion application_name: Firefox 19:04:31 INFO - mozversion application_remotingname: firefox 19:04:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:04:31 INFO - mozversion application_vendor: Mozilla 19:04:31 INFO - mozversion application_version: 49.0a1 19:04:31 INFO - mozversion platform_buildid: 20160605163003 19:04:31 INFO - mozversion platform_changeset: e54065dbe41dc0192f5d1710ad1ba5855f573545 19:04:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:04:31 INFO - mozversion platform_version: 49.0a1 19:04:31 INFO - using testdate: 1465178671 19:04:31 INFO - actual date: 1465178671 19:04:31 INFO - starting webserver on 'localhost:52935' 19:04:31 INFO - SUITE-START | Running 2 tests 19:04:31 INFO - TEST-START | dromaeo_css 19:04:31 INFO - operating with platform_type : linux_ 19:04:31 INFO - Initialising browser for dromaeo_css test... 19:04:31 INFO - TEST-INFO | started process 6350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr7_OhY/profile http://localhost:52935/getInfo.html) 19:04:35 INFO - PROCESS | 6350 | __metrics Screen width/height:1600/1200 19:04:35 INFO - PROCESS | 6350 | colorDepth:24 19:04:35 INFO - PROCESS | 6350 | Browser inner width/height: 1024/697 19:04:35 INFO - PROCESS | 6350 | __metrics 19:04:35 ERROR - PROCESS | 6350 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:04:36 INFO - PROCESS | 6350 | console.error: PushService: 19:04:36 INFO - PROCESS | 6350 | stateChangeProcessEnqueue: Error transitioning state 19:04:36 INFO - PROCESS | 6350 | UnknownError 19:04:36 INFO - TEST-INFO | 6350: exit 0 19:04:36 INFO - Browser initialized. 19:04:36 INFO - Running cycle 1/1 for dromaeo_css test... 19:04:36 INFO - TEST-INFO | started process 6433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr7_OhY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:48 INFO - PROCESS | 6433 | RSS: Main: 156205056 19:04:48 INFO - PROCESS | 6433 | 19:05:39 INFO - PROCESS | 6433 | 1465178739269 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] 19:07:47 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:52935/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52935/tests/dromaeo/cssquery-ext.html) 19:07:49 INFO - PROCESS | 6433 | RSS: Main: 290201600 19:07:49 INFO - PROCESS | 6433 | 19:10:51 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:52935/tests/dromaeo/cssquery-ext.html (next: http://localhost:52935/tests/dromaeo/cssquery-jquery.html) 19:10:52 INFO - PROCESS | 6433 | RSS: Main: 242991104 19:10:52 INFO - PROCESS | 6433 | 19:13:54 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:52935/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52935/tests/dromaeo/cssquery-mootools.html) 19:13:54 INFO - PROCESS | 6433 | RSS: Main: 252563456 19:13:54 INFO - PROCESS | 6433 | 19:16:56 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:52935/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52935/tests/dromaeo/cssquery-prototype.html) 19:16:57 INFO - PROCESS | 6433 | RSS: Main: 247357440 19:16:57 INFO - PROCESS | 6433 | 19:20:00 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:52935/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52935/tests/dromaeo/cssquery-yui.html) 19:20:00 INFO - PROCESS | 6433 | RSS: Main: 226131968 19:20:00 INFO - PROCESS | 6433 | 19:23:03 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:52935/tests/dromaeo/cssquery-yui.html (next: http://localhost:52935/tests/dromaeo/cssquery-dojo.html) 19:23:03 INFO - PROCESS | 6433 | RSS: Main: 256798720 19:23:03 INFO - PROCESS | 6433 | 19:23:03 INFO - PROCESS | 6433 | __start_tp_report 19:23:03 INFO - PROCESS | 6433 | _x_x_mozilla_page_load 19:23:03 INFO - PROCESS | 6433 | _x_x_mozilla_page_load_details 19:23:03 INFO - PROCESS | 6433 | |i|pagename|runs| 19:23:03 INFO - PROCESS | 6433 | |0;dojo.html;3160.0677800048984;3174.6507884132725;3197.056868223873;3214.212517933107;3233.5149727540866;5354.955783622186;5435.048866448371;5450.427705090958;5533.6956467394275;5565.471280228382;5441.238226648263;5488.862778430541;5525.11598144297;5565.471280228372;5586.190002449643;4933.901321973567;4958.925616115743;4959.206526955688;5049.873753156164;5052.924206136892;5029.446760856303;5115.181570948649;5115.795368185268;5125.769340379673;5169.147090730025;4918.114739346916;4948.6288528360155;4989.376327959005;5001.474845141262;5032.4212715537815;5005.824796132118;5005.849824505271;5008.974955125237;5012.498750124995;5013.348264725599;4977.651564390494;4980.850574482735;4981.850544483671;4987.401511818605;4989.925151122736;5344.706041167734;5347.331583552056;5400.91898621521;5409.594280428983;5411.945880541183;5785.508231800302;5792.623479473821;5804.9419505805245;5853.619514731571;5854.5023672987845;5908.261467316585;5908.704564771744;5915.201447804534;5916.171735956927;5920.763169473216;5299.470052994709;5448.645838020556;5479.99999999996;5490.396056433789;5495.53287970519;6020.488258497989;6038.63768173911;6044.332383684575;6048.758049677964;6090.056041313604;5843.474087332075;5849.824505264849;5854.531637468992;5855.268091488564;5857.912131318034;5713.028785106542;5830.504407125357;5868.383819698955;5914.0241860093465;5914.9704251479325;5810.302763668303;5810.709464526757;5813.215215945877;5816.767329306865;5820.330661973927;5638.718064096779;5823.621464604787;5824.184614154022;5836.795712150054;5840.3575606683235;4308.46144231971;4340.305551111807;4361.389405483234;4390.824367025347;4400.09797991411;3930.744501607386;3931.1154990127566;3940.474946639395;3965.20695860829;3975.8668779398313;5507.256520369779;5543.833684989457;5550.4449555044985;5553.055980483327;5554.250176226238;5691.800786972436;5826.271716035496;5832.475077243069;5856.4143585641095;5906.5570082244;5516.503514683698;5518.034343989866;5524.088525393346;5525.060739674265;5528.695921724344;4930.827421040318;5009.273655319958;5014.498550144956;5072.543471087546;5115.155999260155;6050.727717252734;6110.541709371815;6113.997365084942;6116.694165291807;6171.506279497629;5926.288845338587;5929.140274660236;5929.258842644669;5931.881362372815;5980.700964951822;5885.293764748087;6057.212562366864;6113.327533971348;6139.969300153471;6140.508759299335;5764.13537969308;5841.591088623755;5843.970780146072;5846.298444186555;5900.852478687895;5915.763369465345;5969.940300596939;5970.731317090655;5977.7310021050025;5978.611390259619;5505.917411238915;5741.799037033684;5791.623544469596;5816.127580862735;5837.708114594168;5703.857403564944;5720.713964301685;5730.111832665943;5730.312362516524;5764.596478246483;5614;5624.57815663835;5630.155476678367;5631.577631677558;5633.0423827948525;5630.831075067755;5694.259746232963;5699.202111704448;5743.4256574342235;5770.6249093808765;5471.316085489314;5496.5053145217125;5497.39528651856;5550.27846379968;5580.2466666999435 19:23:03 INFO - PROCESS | 6433 | |1;ext.html;581.20375086132;613.1109890658545;618.0636335951035;624.8687775567131;633.5691729623856;42793;43909.78045109778;43996.56003439965;44033.55966440339;47567.71890529852;44553.77723111388;44855.1028979421;44981.7750911245;45053.7747311263;45244;8094.190580941889;8600.096989816073;8744.169303916118;8746;10513.106385957202;10276.794464110713;10673.412962287071;10695.83956240653;10696.73258168548;10698.358098514074;10762.623308184176;10834.079103276192;10836.07893329068;10836.970487803646;10843.783124337508;8227.79430514239;10598.78802423955;10730.517126729315;10857;10867.184961127869;10402.53188606507;10489.265751397405;10497.842532361945;10499.107575855985;10499.527521261485;41374.37938430926;41704;41717.37423938643;41750.37374439386;41786;3548.183917698918;3569.3396721606587;3626.818659067036;3657.6708096271195;3679.3929001714687;32426.513602295985;32610.67389326124;32930.50604240938;34214.14464638379;34286;4300.8709738707885;4371.388005679208;4383.890402739925;4402.405675233835;4430.224710675619;336003;336481;336763;337519;338153;57888.71055644779;57891.710541447865;58198.41801582015;58227.12659310114;58296.4170358291;31976.680233197836;32609.184770380456;32727.50908736371;32735.67264327326;60666.696666516385;16380.83619163793;16482.093484858447;16538.751918721227;16547.586310342336;16562.254698538596;11417.714557136138;11717.531298747954;11814;11850;12060.216085954386;19551.706724399144;19632.70550941737;19637.90181049114;19770.813751174974;19791.60416791656;7262.993887579363;7357.448191385667;7456.701731930662;7482.738104166437;7508.061492313461;985.7929834734786;1006.9093781559695;1091.406541474941;1108.7837871614959;1210.8910198082215;32221.35557288841;32435.837820811215;32451.51322730161;32826.83586582052;33385.49921751176;1120.2662256222188;1129.1869853705318;1132.326265871805;1156.8380426740096;1218.8842060004285;17144.485665430057;17201.397951071904;17309.65380692379;17437.912810435868;17573.560660983578;541380;542363;544535;545526;546499;57455;57787.422125778205;57920.71039644775;57972;57973.420265796805;1285.575759999198;1356.2947267421084;1416.3201663201514;1419.7586410310066;1428.550006747871;32303;32367.51448728224;33389.83305083459;33423.83288083544;34066.318673625894;49332.50667493423;49737.75131124321;49792.50207497879;49863.00547978185;49966;386016;386860;387670;387782;388660;24993.375165621004;44572;44731.55268447404;45089.77455112703;45337.54662453333;46295.76852115718;47138.52861471342;50294.49705502898;51132.23301650404;51620.48379516157;40237.1952560953;41242.38136427896;41645.375319369625;44684.32973505335;45946;42014;42323.365149522164;42771;43108.955283101415;43392;42205.36691949562;42507.57492425036;42662.78668606637;43279.78360108179;43516;35936.38286277071;37471.625283747904;37849.81075094607;38398;38890.4166437498;28773.568396473653;28966.13101606955;29019.83803251248;29211.41577168487;29354.945153839028 19:23:03 INFO - PROCESS | 6433 | |2;jquery.html;400.93585026395783;406.1207485793258;407.1856287425148;407.7716478771888;421.06733585108975;18804.717929231054;19597.216111355545;19626.803731962693;19804.801951980477;20458.181672733073;18166.72749908749;18574.34989775355;19049.42851714447;19077.42767716967;19222.711659325123;5324.920126198101;5504.339479262494;5508.7521061552325;5570.442955704438;5572.192032155355;5564.805231816867;5632.633878797886;5639.295088113986;5644.971775141119;5646.887062258752;5603;5630.971845140769;5923.052311630118;5927;6019.067044608071;5866.911996320058;5926.466618004379;5931.436513531207;5934;5937.376575459558;5617.971910140464;5755.165501002373;5790.826275211751;5801.535877129862;5806.912896306559;13558.93220533891;13739.656508587364;13804.341903879726;13996.790048149285;13997.30013499321;2162.826973842089;2184.2791878680155;2275.3628983884537;2277.3851060213815;2359.3865594945264;9952.701418957442;12832.422540985677;13601.31993400326;13895.722085558333;14597.927010364987;1804.3414153833887;1825.7170138628535;1914.0525439907196;1943.038196092929;1964.0572525187847;77533;77612;77756.61121694412;78244.26145084952;78554.60722696407;11028.172887033505;11090.778184436427;11137.610183643534;11278.943605282086;11432.94283528577;15698;15740.449084282223;15818.130002849724;15851;15862.127582982816;7809.68761249555;8054.436189466681;8120.796980075427;8238.258556729923;8328.625211865481;7656.693732250647;7705.807354816062;7721.305082542598;7808.882866757003;7844.450888437755;4724.95275047252;4789.3294938708605;4794.496577859344;4849.781759820817;4851.490593487633;1976.5552750630993;2005.1478121798177;2023.7773844877051;2024.6760518317294;2040.8265297449586;874.1040433555656;979.118793086228;1006.3659894266565;1062.925595208328;1077.6789822910278;18425.629748100866;18643;19297.614047718966;19564.510887227934;19580.216791328472;966.1015255812022;1028.711960651019;1052.369077306733;1053.5575058475458;1060.3637817309552;10432.634857779942;10817.675469735921;10892.564297428173;10942.671719848417;10993.890061099286;477650;480484;485968;494934;495461;85159.57420212984;88511.55744221238;88650;89994.55002725075;90061.09938900528;1014.9797004059877;1038.9376637401629;1057.9259451838298;1062.1449732964884;1069.684443089275;17060.573485662957;17091.743623845403;17337.306507739548;17395.95624262548;17626.735598966283;39749.60250397459;40681.18637627172;40788.59211407848;40919.38620920629;42228.78885605552;56726.14910776424;57166.14250786159;57793.84412311646;57819.71090144522;59614.105788412344;8352.540610266493;8811.471311721318;8814.294856411343;8921.955390223007;8994.370394072352;12574.434150463434;13299.534516291884;13321;13323.866761332263;13431.597052088453;11436.142289328167;11624.256361545837;11661.183717139718;11808.409579521162;11957.342346170695;11520;11595.304281743123;11617.419129043345;11807.586734464252;11919;11884.524619015143;11896.345700986529;11929.463174157358;12091.274523528617;12163.087768417226;8516;8677.219050285505;8733.737987860375;8756.649734010569;8834.514101724468;8860.601272942862;11563.45982364028;11696.298222106361;11699.824502632637;11796.94101529487 19:23:03 INFO - PROCESS | 6433 | |3;mootools.html;1283.9614811555657;1512.0398546922702;1533.5476034569801;1681.4787415901071;1831.3041044403126;8705.303575713935;9018.09819018098;9043.457392556451;9322;9339.953300233507;9006.774830629238;9231.123043310892;9241.676541321056;9247.768805779859;9247.861282080758;2034.6337659221335;2367.6922000140044;2405.5910495215853;2436.5735996200683;2442.829001969863;2389.354874183969;2397.856128632279;2398.1846172301416;2402.8798560072014;2404.915827946022;2336.5210131923;2400.939976500584;2402.531506356261;2403.375122468153;2406.554787364343;2033.471297462663;2345.765423457649;2405.867677277749;2410.554047501218;2414.3360575841607;2341.555104530134;2342.5197834443898;2346.2843832631042;2346.6128088865316;2379.892904819287;9033.954830225806;9183.127602877783;9183.265338772882;9190.126937940884;9191.540422978824;5321.308229930123;5470.152126420411;5472.151816468454;5475.534579560742;5480.369757477862;3164.651888292286;3255.788373755698;3325.983370083134;3374.628790833031;3382.8985130446126;991.9256055795844;1023.7542989682422;1035.9223058270584;1045.2578669144916;1062.6015244283394;12842.229466232056;12865.67835804103;12874.742505149938;12905;12939.09426340166;4708.745931045312;4806.975965120152;4861.4895435978715;4921.188003979375;4923.48303428142;10767.300125491975;10793.946030269799;10797.352158870493;10800.351978881292;10808.459577021118;4877.292792545061;5443.129099344165;5456.072467680504;5459.69971651563;5463.781448742086;4872.43966943806;4995.975020124876;5009.949900500948;5050.1667224908215;5064.620153488503;4098.590140985937;4160.83356665736;4163.604457576525;4199.307114326163;4209.389638502461;1285.8971282297393;1316.2290039038278;1325.0790700463278;1326.3633455941201;1337.2444568818587;957.8802649668792;965.584798536636;973.8101070291225;976.7069879036261;991.311038828022;5808.30300363959;5893.557983151195;5936.376680448577;6027.156198132265;6132.724027418777;901.5717534171216;931.8602209668604;950.6435086842434;958.5925981457852;1016.0753714120262;3773.9245215095543;3828.7511311764647;3875.050612599931;3946.7039972002217;3963.564007959122;11112.499937502831;11255.43722813856;11423.828642570368;11494.195406321645;11512.366819824823;11160.609378671708;11305;11317.207795454238;11388.829167562493;11398.316101033965;8257.95871020641;8674.349423793241;8735.868961965576;8759.080296568896;8773.341999349946;5628.352739434854;5667.716614169358;5738.22533957927;5792.275965504312;5834.679092649945;10634.30876992993;10784.299020563638;10791.6222932197;10802;10806.459677016275;10513.737156571147;10877.238593298393;10930.508127134139;11005;11016.779664406828;6424.550281480251;7025.613591252531;7185.06594142779;7250.818729531804;7368.005319282042;8734;8796.164364385373;8820.823583528421;8860.601272942604;8881.911180888366;7917.683292668229;8092.919070809452;8108.607222180676;8214;8214.260716535302;7892.36861051126;7934.761957141295;7952.966114405091;8053.436259461781;8084.514929104273;6673.399394054559;6730.495212859153;6753.1220941277315;6805.625690587065;6822.0108084328485;4933.753312334441;5002.899942001212;5013.448520662797;5013.974930125326;5077.720725360141;5098.719570423662;5113.3097031900215;5167.22491626244;5191.766370513412;5344.144936810092 19:23:04 INFO - PROCESS | 6433 | |4;prototype.html;321.855165175671;335.2875140326806;336.76931302058074;338.6393490932157;340.82788191963056;5128.461511541285;5245.554127899129;5305.071612467822;5337.6797392156495;5377.731113444327;5239.790408383669;5285.867853303669;5297.496737809912;5307.20391941209;5314.813981510638;4164.875053748392;4183.309753890604;4207.663386929054;4213.978930105361;4214.97892510537;4033.9596604034023;4164.75011499311;4180.352045432947;4189.162167566484;4223.239816832969;4126.834926602933;4142.212979533879;4143.523494798092;4150.5265630701115;4178;4151.14901445206;4156.875293741193;4187.895302617444;4190.916181676349;4203.684723645739;3661.6887564557046;3725.8137093145233;3733.831977561016;3743.3262012837677;3743.850245990157;5074.188129899198;5076.187809950427;5079.771410286546;5085.694858308476;5104.183330667075;4721;4728.905421891577;4728.976355118202;4733.390647179941;4745.359376484165;5256.684598924076;5400.459954004607;5403.838174792439;5410.702411367373;5415.566754659657;4033.193361327746;4038.8788336349958;4041.2321658884716;4046.5953404659595;4055.3511438170044;5451.754671039813;5562.413477732874;5758.021136406821;5813.651180929115;5823.883522329572;4090.570490098497;4093.2427500912427;4126.154138401621;4131.235721391552;4147.771872546979;4164.6251837334785;4166.812493437803;4172.874813755592;4183.372494125844;4191.3293872980785;4529.162105010583;4639.512851150581;4707.270373092176;4714.5992590630185;4744.4069491313585;5464.23500709901;5475.123980163155;5477.7261136943;5486.670799752028;5496.395396506381;1428.057482061855;1444.9885080443732;1454.3600815641296;1461.8903582231376;1465.1648560320727;918.830016446946;935.1770442010986;938.15565990609;954.725441535544;971.2569884038716;4675.322078298627;4704.553067458586;4705.905881882343;4740.715557066587;4759.286107083896;4974.801007959713;5004.149294619923;5016.2224855147515;5017.698938063655;5019.623528235397;4720.645951553647;4727.54167832725;4736.526347365236;4771.66598338113;4928.186849169847;3026.243439140215;3034.893778717734;3050.9847450762604;3052.8168309901475;3059.143439836849;5961.314448838352;6000;6007.519398448114;6015.0977353396465;6022.518198544106;5655;5870.589058765845;5881.852953676193;5883.264591926009;5886.3230728465915;5861.17943487912;5884.175905469318;5913.70431478433;5930.495907847795;5988.580799344091;5557.472040156179;5609.663420194802;5734.426557344233;5737.799177028784;5750.568707347049;5328.57371410294;5621.184928185308;5630.971845140748;5636.182753500638;5654.660720356792;5763.337216220189;5891.734871930859;5891.970540147443;5904.586678932434;5929.636183677692;3996.160806230724;4012.53855806586;4019.93970090443;4027.134166154291;4057.7159598827798;5407.648502847303;5639;5646.237757902795;5751.8562035949435;5761.798337058183;5573.721313934206;5584.162375643686;5608.046632072639;5629.155626656035;5646.463585959327;5545.7781688732;5555.805546805843;5571;5610;5613.410591887793;5165.9483405166975;5454.527277634937;5467.808626698046;5470.945290547043;5487.890242195213;5552.416996215339;5596.776128954796;5598.860028499157;5606.159076138611;5607.523360514238;5181.248718935809;5433.0169488137335;5463.448758307132;5486.807961721321;5499.3675727291875 19:23:04 INFO - PROCESS | 6433 | |5;yui.html;689.3726708695089;700.2227527444536;712.6828561290224;810.8256724804168;879.2702057292446;14594.63513412164;15059.924700376496;15064.774028389584;15317.69364612707;15330.463433779805;14673.413063477448;15089;15132.394704211845;15148.848511514909;15161.469348572802;690.1028662738445;815.6941147069834;830.2818062376061;830.9916900831005;835.9498430094184;805.842860642175;834.995825020874;836.0510820219065;841.6338892581716;841.9957900210491;868.7306934850185;868.9869651955194;869.5087275689247;869.9565021748919;871.337783284705;720.6504845150098;843.696269343036;866.5017614871442;866.9046404895456;867.2584939876378;626.2234828812285;630.4546567219368;630.9022101574263;631.8546734251101;631.962082275065;15021.774673379909;15032.699346013018;15057.774133388008;15073.095614263179;15086.471973480875;13049.151805132637;13052.412641431158;13219.86780132196;13257.270850103241;14191.148531088167;12425.31660758658;12469.937650311782;12540;12545.372731363395;12709.491620335268;4368.956310436918;4392.824287028515;4468.4414448193975;4497.302918047708;4501.41481607389;72951;73442;73638.63180684116;73984;74169.25830741678;10943.726406839893;11077.72305692348;11249.66251012471;11313.208075434804;11407;21167.78832211689;21183.78816211849;21187.15251389958;21208.15167393318;21224.25715099964;8531.402801803939;8570.314374849997;8624.482531048157;8680.56597170139;8909.465432074096;4615.26155815068;4661.883452913704;4690.929636055462;4696.647751418657;4899.926501102486;3273.688999545087;3379.3579219948133;3383.3571621391857;3386.4581666933173;3389.305192435545;577.1314172170856;577.7602295047508;579.7101449275362;594.6996766632909;602.2892986276148;517.1292872846814;536.407269966689;542.6798189068469;613.4999975020344;615.5198944823045;9098.772530686654;9258.814823703487;9301.209397201314;9381.249500040118;9532.18976387001;600.7206648908278;613.1293563140351;624.4660815003198;644.3893938867411;662.9171353580803;4384.868453946387;4395.494518130392;4413.757243351646;4643.396358473445;4657.930131048037;64434.67782661151;65669;65839.67080164664;65923.67038164778;98769.50615246878;91178;91181.54409227912;92085.53957230171;92880;92966;80515;81176.18823811805;81434.59282703549;81551.59224203842;82504.17495825085;8671.739847804576;8786.25316848075;8790.340724445565;8876.245519130945;9303.86044209351;49734.75132624314;49994.75002624963;51027.2345914819;51040.23439648334;52203.477965221384;44714;51313;73492;73799.63100184465;74344;11619.670955305819;11899.226550274205;11949;11952;12074.39628018613;12458.127931044739;12517.749645006867;13003.414846332127;13023.218606883618;13352.465901363837;12099.516019359127;12499.250044997329;12522.186057906207;12702.30137342455;12795.603281637232;11254.662360129209;11290.661280161608;11551.306921584732;11626.825597615873;11639.301641901513;11428.714282143013;11762.58830940913;11868.584599538975;11869.821952670543;11870.169088164092;9061.501617410842;9192.126747958933;9491.288153388661;9492.382995105296;9872.160866326443;9581.712548623553;10082.495875206358;10091.404025773056;10138.79722405533;10151.796964060824 19:23:04 INFO - PROCESS | 6433 | __end_tp_report 19:23:04 INFO - PROCESS | 6433 | __start_cc_report 19:23:04 INFO - PROCESS | 6433 | _x_x_mozilla_cycle_collect,1116 19:23:04 INFO - PROCESS | 6433 | __end_cc_report 19:23:04 INFO - PROCESS | 6433 | __startTimestamp1465179783991__endTimestamp 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | ------- Summary: start ------- 19:23:04 INFO - PROCESS | 6433 | Number of tests: 6 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | [#0] dojo.html Cycles:165 Average:5443.62 Median:5624.58 stddev:627.54 (11.2%) stddev-sans-first:603.36 19:23:04 INFO - PROCESS | 6433 | Values: 3160.1 3174.7 3197.1 3214.2 3233.5 5355.0 5435.0 5450.4 5533.7 5565.5 5441.2 5488.9 5525.1 5565.5 5586.2 4933.9 4958.9 4959.2 5049.9 5052.9 5029.4 5115.2 5115.8 5125.8 5169.1 4918.1 4948.6 4989.4 5001.5 5032.4 5005.8 5005.8 5009.0 5012.5 5013.3 4977.7 4980.9 4981.9 4987.4 4989.9 5344.7 5347.3 5400.9 5409.6 5411.9 5785.5 5792.6 5804.9 5853.6 5854.5 5908.3 5908.7 5915.2 5916.2 5920.8 5299.5 5448.6 5480.0 5490.4 5495.5 6020.5 6038.6 6044.3 6048.8 6090.1 5843.5 5849.8 5854.5 5855.3 5857.9 5713.0 5830.5 5868.4 5914.0 5915.0 5810.3 5810.7 5813.2 5816.8 5820.3 5638.7 5823.6 5824.2 5836.8 5840.4 4308.5 4340.3 4361.4 4390.8 4400.1 3930.7 3931.1 3940.5 3965.2 3975.9 5507.3 5543.8 5550.4 5553.1 5554.3 5691.8 5826.3 5832.5 5856.4 5906.6 5516.5 5518.0 5524.1 5525.1 5528.7 4930.8 5009.3 5014.5 5072.5 5115.2 6050.7 6110.5 6114.0 6116.7 6171.5 5926.3 5929.1 5929.3 5931.9 5980.7 5885.3 6057.2 6113.3 6140.0 6140.5 5764.1 5841.6 5844.0 5846.3 5900.9 5915.8 5969.9 5970.7 5977.7 5978.6 5505.9 5741.8 5791.6 5816.1 5837.7 5703.9 5720.7 5730.1 5730.3 5764.6 5614.0 5624.6 5630.2 5631.6 5633.0 5630.8 5694.3 5699.2 5743.4 5770.6 5471.3 5496.5 5497.4 5550.3 5580.2 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | [#1] ext.html Cycles:180 Average:58992.36 Median:37660.72 stddev:114310.38 (303.5%) stddev-sans-first:114546.92 19:23:04 INFO - PROCESS | 6433 | Values: 581.2 613.1 618.1 624.9 633.6 42793.0 43909.8 43996.6 44033.6 47567.7 44553.8 44855.1 44981.8 45053.8 45244.0 8094.2 8600.1 8744.2 8746.0 10513.1 10276.8 10673.4 10695.8 10696.7 10698.4 10762.6 10834.1 10836.1 10837.0 10843.8 8227.8 10598.8 10730.5 10857.0 10867.2 10402.5 10489.3 10497.8 10499.1 10499.5 41374.4 41704.0 41717.4 41750.4 41786.0 3548.2 3569.3 3626.8 3657.7 3679.4 32426.5 32610.7 32930.5 34214.1 34286.0 4300.9 4371.4 4383.9 4402.4 4430.2 336003.0 336481.0 336763.0 337519.0 338153.0 57888.7 57891.7 58198.4 58227.1 58296.4 31976.7 32609.2 32727.5 32735.7 60666.7 16380.8 16482.1 16538.8 16547.6 16562.3 11417.7 11717.5 11814.0 11850.0 12060.2 19551.7 19632.7 19637.9 19770.8 19791.6 7263.0 7357.4 7456.7 7482.7 7508.1 985.8 1006.9 1091.4 1108.8 1210.9 32221.4 32435.8 32451.5 32826.8 33385.5 1120.3 1129.2 1132.3 1156.8 1218.9 17144.5 17201.4 17309.7 17437.9 17573.6 541380.0 542363.0 544535.0 545526.0 546499.0 57455.0 57787.4 57920.7 57972.0 57973.4 1285.6 1356.3 1416.3 1419.8 1428.6 32303.0 32367.5 33389.8 33423.8 34066.3 49332.5 49737.8 49792.5 49863.0 49966.0 386016.0 386860.0 387670.0 387782.0 388660.0 24993.4 44572.0 44731.6 45089.8 45337.5 46295.8 47138.5 50294.5 51132.2 51620.5 40237.2 41242.4 41645.4 44684.3 45946.0 42014.0 42323.4 42771.0 43109.0 43392.0 42205.4 42507.6 42662.8 43279.8 43516.0 35936.4 37471.6 37849.8 38398.0 38890.4 28773.6 28966.1 29019.8 29211.4 29354.9 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | [#2] jquery.html Cycles:180 Average:28544.24 Median:2173.55 stddev:80183.38 (3689.0%) stddev-sans-first:80380.47 19:23:04 INFO - PROCESS | 6433 | Values: 400.9 406.1 407.2 407.8 421.1 18804.7 19597.2 19626.8 19804.8 20458.2 18166.7 18574.3 19049.4 19077.4 19222.7 5324.9 5504.3 5508.8 5570.4 5572.2 5564.8 5632.6 5639.3 5645.0 5646.9 5603.0 5631.0 5923.1 5927.0 6019.1 5866.9 5926.5 5931.4 5934.0 5937.4 5618.0 5755.2 5790.8 5801.5 5806.9 13558.9 13739.7 13804.3 13996.8 13997.3 2162.8 2184.3 2275.4 2277.4 2359.4 9952.7 12832.4 13601.3 13895.7 14597.9 1804.3 1825.7 1914.1 1943.0 1964.1 77533.0 77612.0 77756.6 78244.3 78554.6 11028.2 11090.8 11137.6 11278.9 11432.9 15698.0 15740.4 15818.1 15851.0 15862.1 7809.7 8054.4 8120.8 8238.3 8328.6 7656.7 7705.8 7721.3 7808.9 7844.5 4725.0 4789.3 4794.5 4849.8 4851.5 1976.6 2005.1 2023.8 2024.7 2040.8 874.1 979.1 1006.4 1062.9 1077.7 18425.6 18643.0 19297.6 19564.5 19580.2 966.1 1028.7 1052.4 1053.6 1060.4 10432.6 10817.7 10892.6 10942.7 10993.9 477650.0 480484.0 485968.0 494934.0 495461.0 85159.6 88511.6 88650.0 89994.6 90061.1 1015.0 1038.9 1057.9 1062.1 1069.7 17060.6 17091.7 17337.3 17396.0 17626.7 39749.6 40681.2 40788.6 40919.4 42228.8 56726.1 57166.1 57793.8 57819.7 59614.1 8352.5 8811.5 8814.3 8922.0 8994.4 12574.4 13299.5 13321.0 13323.9 13431.6 11436.1 11624.3 11661.2 11808.4 11957.3 11520.0 11595.3 11617.4 11807.6 11919.0 11884.5 11896.3 11929.5 12091.3 12163.1 8516.0 8677.2 8733.7 8756.6 8834.5 8860.6 11563.5 11696.3 11699.8 11796.9 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | [#3] mootools.html Cycles:180 Average:5952.21 Median:4922.34 stddev:3473.95 (70.6%) stddev-sans-first:3465.98 19:23:04 INFO - PROCESS | 6433 | Values: 1284.0 1512.0 1533.5 1681.5 1831.3 8705.3 9018.1 9043.5 9322.0 9340.0 9006.8 9231.1 9241.7 9247.8 9247.9 2034.6 2367.7 2405.6 2436.6 2442.8 2389.4 2397.9 2398.2 2402.9 2404.9 2336.5 2400.9 2402.5 2403.4 2406.6 2033.5 2345.8 2405.9 2410.6 2414.3 2341.6 2342.5 2346.3 2346.6 2379.9 9034.0 9183.1 9183.3 9190.1 9191.5 5321.3 5470.2 5472.2 5475.5 5480.4 3164.7 3255.8 3326.0 3374.6 3382.9 991.9 1023.8 1035.9 1045.3 1062.6 12842.2 12865.7 12874.7 12905.0 12939.1 4708.7 4807.0 4861.5 4921.2 4923.5 10767.3 10793.9 10797.4 10800.4 10808.5 4877.3 5443.1 5456.1 5459.7 5463.8 4872.4 4996.0 5009.9 5050.2 5064.6 4098.6 4160.8 4163.6 4199.3 4209.4 1285.9 1316.2 1325.1 1326.4 1337.2 957.9 965.6 973.8 976.7 991.3 5808.3 5893.6 5936.4 6027.2 6132.7 901.6 931.9 950.6 958.6 1016.1 3773.9 3828.8 3875.1 3946.7 3963.6 11112.5 11255.4 11423.8 11494.2 11512.4 11160.6 11305.0 11317.2 11388.8 11398.3 8258.0 8674.3 8735.9 8759.1 8773.3 5628.4 5667.7 5738.2 5792.3 5834.7 10634.3 10784.3 10791.6 10802.0 10806.5 10513.7 10877.2 10930.5 11005.0 11016.8 6424.6 7025.6 7185.1 7250.8 7368.0 8734.0 8796.2 8820.8 8860.6 8881.9 7917.7 8092.9 8108.6 8214.0 8214.3 7892.4 7934.8 7953.0 8053.4 8084.5 6673.4 6730.5 6753.1 6805.6 6822.0 4933.8 5002.9 5013.4 5014.0 5077.7 5098.7 5113.3 5167.2 5191.8 5344.1 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | [#4] prototype.html Cycles:180 Average:4631.98 Median:5147.20 stddev:1345.78 (26.1%) stddev-sans-first:1310.10 19:23:04 INFO - PROCESS | 6433 | Values: 321.9 335.3 336.8 338.6 340.8 5128.5 5245.6 5305.1 5337.7 5377.7 5239.8 5285.9 5297.5 5307.2 5314.8 4164.9 4183.3 4207.7 4214.0 4215.0 4034.0 4164.8 4180.4 4189.2 4223.2 4126.8 4142.2 4143.5 4150.5 4178.0 4151.1 4156.9 4187.9 4190.9 4203.7 3661.7 3725.8 3733.8 3743.3 3743.9 5074.2 5076.2 5079.8 5085.7 5104.2 4721.0 4728.9 4729.0 4733.4 4745.4 5256.7 5400.5 5403.8 5410.7 5415.6 4033.2 4038.9 4041.2 4046.6 4055.4 5451.8 5562.4 5758.0 5813.7 5823.9 4090.6 4093.2 4126.2 4131.2 4147.8 4164.6 4166.8 4172.9 4183.4 4191.3 4529.2 4639.5 4707.3 4714.6 4744.4 5464.2 5475.1 5477.7 5486.7 5496.4 1428.1 1445.0 1454.4 1461.9 1465.2 918.8 935.2 938.2 954.7 971.3 4675.3 4704.6 4705.9 4740.7 4759.3 4974.8 5004.1 5016.2 5017.7 5019.6 4720.6 4727.5 4736.5 4771.7 4928.2 3026.2 3034.9 3051.0 3052.8 3059.1 5961.3 6000.0 6007.5 6015.1 6022.5 5655.0 5870.6 5881.9 5883.3 5886.3 5861.2 5884.2 5913.7 5930.5 5988.6 5557.5 5609.7 5734.4 5737.8 5750.6 5328.6 5621.2 5631.0 5636.2 5654.7 5763.3 5891.7 5892.0 5904.6 5929.6 3996.2 4012.5 4019.9 4027.1 4057.7 5407.6 5639.0 5646.2 5751.9 5761.8 5573.7 5584.2 5608.0 5629.2 5646.5 5545.8 5555.8 5571.0 5610.0 5613.4 5165.9 5454.5 5467.8 5470.9 5487.9 5552.4 5596.8 5598.9 5606.2 5607.5 5181.2 5433.0 5463.4 5486.8 5499.4 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - PROCESS | 6433 | [#5] yui.html Cycles:180 Average:18538.62 Median:26360.30 stddev:25431.30 (96.5%) stddev-sans-first:25467.33 19:23:04 INFO - PROCESS | 6433 | Values: 689.4 700.2 712.7 810.8 879.3 14594.6 15059.9 15064.8 15317.7 15330.5 14673.4 15089.0 15132.4 15148.8 15161.5 690.1 815.7 830.3 831.0 835.9 805.8 835.0 836.1 841.6 842.0 868.7 869.0 869.5 870.0 871.3 720.7 843.7 866.5 866.9 867.3 626.2 630.5 630.9 631.9 632.0 15021.8 15032.7 15057.8 15073.1 15086.5 13049.2 13052.4 13219.9 13257.3 14191.1 12425.3 12469.9 12540.0 12545.4 12709.5 4369.0 4392.8 4468.4 4497.3 4501.4 72951.0 73442.0 73638.6 73984.0 74169.3 10943.7 11077.7 11249.7 11313.2 11407.0 21167.8 21183.8 21187.2 21208.2 21224.3 8531.4 8570.3 8624.5 8680.6 8909.5 4615.3 4661.9 4690.9 4696.6 4899.9 3273.7 3379.4 3383.4 3386.5 3389.3 577.1 577.8 579.7 594.7 602.3 517.1 536.4 542.7 613.5 615.5 9098.8 9258.8 9301.2 9381.2 9532.2 600.7 613.1 624.5 644.4 662.9 4384.9 4395.5 4413.8 4643.4 4657.9 64434.7 65669.0 65839.7 65923.7 98769.5 91178.0 91181.5 92085.5 92880.0 92966.0 80515.0 81176.2 81434.6 81551.6 82504.2 8671.7 8786.3 8790.3 8876.2 9303.9 49734.8 49994.8 51027.2 51040.2 52203.5 44714.0 51313.0 73492.0 73799.6 74344.0 11619.7 11899.2 11949.0 11952.0 12074.4 12458.1 12517.7 13003.4 13023.2 13352.5 12099.5 12499.3 12522.2 12702.3 12795.6 11254.7 11290.7 11551.3 11626.8 11639.3 11428.7 11762.6 11868.6 11869.8 11870.2 9061.5 9192.1 9491.3 9492.4 9872.2 9581.7 10082.5 10091.4 10138.8 10151.8 19:23:04 INFO - PROCESS | 6433 | -------- Summary: end -------- 19:23:04 INFO - PROCESS | 6433 | 19:23:04 INFO - TEST-INFO | 6433: exit 0 19:23:04 INFO - TEST-OK | dromaeo_css | took 1113042ms 19:23:04 INFO - TEST-START | kraken 19:23:04 INFO - operating with platform_type : linux_ 19:23:04 INFO - Initialising browser for kraken test... 19:23:04 INFO - TEST-INFO | started process 6617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBXH7em/profile http://localhost:52935/getInfo.html) 19:23:08 INFO - PROCESS | 6617 | __metrics Screen width/height:1600/1200 19:23:08 INFO - PROCESS | 6617 | colorDepth:24 19:23:08 INFO - PROCESS | 6617 | Browser inner width/height: 1024/697 19:23:08 INFO - PROCESS | 6617 | __metrics 19:23:09 ERROR - PROCESS | 6617 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:23:09 INFO - PROCESS | 6617 | console.error: PushService: 19:23:09 INFO - PROCESS | 6617 | stateChangeProcessEnqueue: Error transitioning state 19:23:09 INFO - PROCESS | 6617 | UnknownError 19:23:09 INFO - TEST-INFO | 6617: exit 0 19:23:09 INFO - Browser initialized. 19:23:09 INFO - Running cycle 1/1 for kraken test... 19:23:09 INFO - TEST-INFO | started process 6697 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBXH7em/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:21 INFO - PROCESS | 6697 | RSS: Main: 159924224 19:23:21 INFO - PROCESS | 6697 | 19:23:24 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52935/tests/kraken/driver.html?testName=audio-beat-detection) 19:23:24 INFO - PROCESS | 6697 | RSS: Main: 219156480 19:23:24 INFO - PROCESS | 6697 | 19:23:27 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52935/tests/kraken/driver.html?testName=audio-dft) 19:23:28 INFO - PROCESS | 6697 | RSS: Main: 205295616 19:23:28 INFO - PROCESS | 6697 | 19:23:31 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52935/tests/kraken/driver.html?testName=audio-fft) 19:23:31 INFO - PROCESS | 6697 | RSS: Main: 272474112 19:23:31 INFO - PROCESS | 6697 | 19:23:34 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52935/tests/kraken/driver.html?testName=audio-oscillator) 19:23:34 INFO - PROCESS | 6697 | RSS: Main: 183259136 19:23:34 INFO - PROCESS | 6697 | 19:23:36 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52935/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:23:37 INFO - PROCESS | 6697 | RSS: Main: 187674624 19:23:37 INFO - PROCESS | 6697 | 19:23:40 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52935/tests/kraken/driver.html?testName=imaging-darkroom) 19:23:41 INFO - PROCESS | 6697 | RSS: Main: 222642176 19:23:41 INFO - PROCESS | 6697 | 19:23:44 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52935/tests/kraken/driver.html?testName=imaging-desaturate) 19:23:44 INFO - PROCESS | 6697 | RSS: Main: 224477184 19:23:44 INFO - PROCESS | 6697 | 19:23:47 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52935/tests/kraken/driver.html?testName=json-parse-financial) 19:23:48 INFO - PROCESS | 6697 | RSS: Main: 225062912 19:23:48 INFO - PROCESS | 6697 | 19:23:49 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52935/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:23:50 INFO - PROCESS | 6697 | RSS: Main: 210890752 19:23:50 INFO - PROCESS | 6697 | 19:23:52 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:23:52 INFO - PROCESS | 6697 | RSS: Main: 215277568 19:23:52 INFO - PROCESS | 6697 | 19:23:54 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:23:55 INFO - PROCESS | 6697 | RSS: Main: 216944640 19:23:55 INFO - PROCESS | 6697 | 19:23:58 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:23:58 INFO - PROCESS | 6697 | RSS: Main: 225665024 19:23:58 INFO - PROCESS | 6697 | 19:24:01 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:24:02 INFO - PROCESS | 6697 | RSS: Main: 225243136 19:24:02 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:52935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52935/tests/kraken/driver.html?testName=ai-astar) 19:24:03 INFO - PROCESS | 6697 | RSS: Main: 267350016 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | __start_tp_report 19:24:03 INFO - PROCESS | 6697 | _x_x_mozilla_page_load 19:24:03 INFO - PROCESS | 6697 | _x_x_mozilla_page_load_details 19:24:03 INFO - PROCESS | 6697 | |i|pagename|runs| 19:24:03 INFO - PROCESS | 6697 | |0;ai-astar;112;108;112;111;111;113;111;111;111;110 19:24:03 INFO - PROCESS | 6697 | |1;audio-beat-detection;157;126;127;191;126;125;148;124;157;125 19:24:03 INFO - PROCESS | 6697 | |2;audio-dft;160;159;161;159;161;160;161;160;162;161 19:24:03 INFO - PROCESS | 6697 | |3;audio-fft;74;75;74;122;76;76;73;74;97;75 19:24:03 INFO - PROCESS | 6697 | |4;audio-oscillator;86;85;113;84;85;87;122;93;85;122 19:24:03 INFO - PROCESS | 6697 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;117;117 19:24:03 INFO - PROCESS | 6697 | |6;imaging-darkroom;145;145;145;146;145;145;146;147;145;144 19:24:03 INFO - PROCESS | 6697 | |7;imaging-desaturate;94;95;94;96;94;95;94;94;95;95 19:24:03 INFO - PROCESS | 6697 | |8;json-parse-financial;54;57;52;54;56;55;54;55;55;55 19:24:03 INFO - PROCESS | 6697 | |9;json-stringify-tinderbox;60;61;61;61;60;61;61;64;60;61 19:24:03 INFO - PROCESS | 6697 | |10;stanford-crypto-aes;81;85;83;79;93;85;81;80;112;85 19:24:03 INFO - PROCESS | 6697 | |11;stanford-crypto-ccm;139;131;124;132;125;127;121;122;144;122 19:24:03 INFO - PROCESS | 6697 | |12;stanford-crypto-pbkdf2;162;162;163;160;158;160;160;159;160;159 19:24:03 INFO - PROCESS | 6697 | |13;stanford-crypto-sha256-iterative;63;66;65;66;63;62;62;71;63;63 19:24:03 INFO - PROCESS | 6697 | __end_tp_report 19:24:03 INFO - PROCESS | 6697 | __start_cc_report 19:24:03 INFO - PROCESS | 6697 | _x_x_mozilla_cycle_collect,1586 19:24:03 INFO - PROCESS | 6697 | __end_cc_report 19:24:03 INFO - PROCESS | 6697 | __startTimestamp1465179843910__endTimestamp 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | ------- Summary: start ------- 19:24:03 INFO - PROCESS | 6697 | Number of tests: 14 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:1.33 (1.2%) stddev-sans-first:1.36 19:24:03 INFO - PROCESS | 6697 | Values: 112.0 108.0 112.0 111.0 111.0 113.0 111.0 111.0 111.0 110.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:137.50 stddev:22.38 (16.3%) stddev-sans-first:22.93 19:24:03 INFO - PROCESS | 6697 | Values: 157.0 126.0 127.0 191.0 126.0 125.0 148.0 124.0 157.0 125.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 19:24:03 INFO - PROCESS | 6697 | Values: 160.0 159.0 161.0 159.0 161.0 160.0 161.0 160.0 162.0 161.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:15.87 (21.2%) stddev-sans-first:16.59 19:24:03 INFO - PROCESS | 6697 | Values: 74.0 75.0 74.0 122.0 76.0 76.0 73.0 74.0 97.0 75.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:85.00 stddev:16.12 (19.0%) stddev-sans-first:16.67 19:24:03 INFO - PROCESS | 6697 | Values: 86.0 85.0 113.0 84.0 85.0 87.0 122.0 93.0 85.0 122.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 19:24:03 INFO - PROCESS | 6697 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 19:24:03 INFO - PROCESS | 6697 | Values: 145.0 145.0 145.0 146.0 145.0 145.0 146.0 147.0 145.0 144.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 19:24:03 INFO - PROCESS | 6697 | Values: 94.0 95.0 94.0 96.0 94.0 95.0 94.0 94.0 95.0 95.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.34 (2.4%) stddev-sans-first:1.39 19:24:03 INFO - PROCESS | 6697 | Values: 54.0 57.0 52.0 54.0 56.0 55.0 54.0 55.0 55.0 55.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:1.15 (1.9%) stddev-sans-first:1.17 19:24:03 INFO - PROCESS | 6697 | Values: 60.0 61.0 61.0 61.0 60.0 61.0 61.0 64.0 60.0 61.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:84.00 stddev:9.83 (11.7%) stddev-sans-first:10.23 19:24:03 INFO - PROCESS | 6697 | Values: 81.0 85.0 83.0 79.0 93.0 85.0 81.0 80.0 112.0 85.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:129.00 stddev:7.78 (6.0%) stddev-sans-first:7.30 19:24:03 INFO - PROCESS | 6697 | Values: 139.0 131.0 124.0 132.0 125.0 127.0 121.0 122.0 144.0 122.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:160.00 stddev:1.57 (1.0%) stddev-sans-first:1.54 19:24:03 INFO - PROCESS | 6697 | Values: 162.0 162.0 163.0 160.0 158.0 160.0 160.0 159.0 160.0 159.0 19:24:03 INFO - PROCESS | 6697 | 19:24:03 INFO - PROCESS | 6697 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.00 stddev:2.76 (4.3%) stddev-sans-first:2.88 19:24:03 INFO - PROCESS | 6697 | Values: 63.0 66.0 65.0 66.0 63.0 62.0 62.0 71.0 63.0 63.0 19:24:03 INFO - PROCESS | 6697 | -------- Summary: end -------- 19:24:03 INFO - PROCESS | 6697 | 19:24:04 INFO - TEST-INFO | 6697: exit 0 19:24:04 INFO - TEST-OK | kraken | took 60113ms 19:24:04 INFO - SUITE-END | took 1173s 19:24:05 INFO - Completed test suite (00:19:33) 19:24:05 INFO - javascript benchmark 19:24:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.0677800048984, 3174.6507884132725, 3197.056868223873, 3214.212517933107, 3233.5149727540866, 5354.955783622186, 5435.048866448371, 5450.427705090958, 5533.6956467394275, 5565.471280228382, 5441.238226648263, 5488.862778430541, 5525.11598144297, 5565.471280228372, 5586.190002449643, 4933.901321973567, 4958.925616115743, 4959.206526955688, 5049.873753156164, 5052.924206136892, 5029.446760856303, 5115.181570948649, 5115.795368185268, 5125.769340379673, 5169.147090730025, 4918.114739346916, 4948.6288528360155, 4989.376327959005, 5001.474845141262, 5032.4212715537815, 5005.824796132118, 5005.849824505271, 5008.974955125237, 5012.498750124995, 5013.348264725599, 4977.651564390494, 4980.850574482735, 4981.850544483671, 4987.401511818605, 4989.925151122736, 5344.706041167734, 5347.331583552056, 5400.91898621521, 5409.594280428983, 5411.945880541183, 5785.508231800302, 5792.623479473821, 5804.9419505805245, 5853.619514731571, 5854.5023672987845, 5908.261467316585, 5908.704564771744, 5915.201447804534, 5916.171735956927, 5920.763169473216, 5299.470052994709, 5448.645838020556, 5479.99999999996, 5490.396056433789, 5495.53287970519, 6020.488258497989, 6038.63768173911, 6044.332383684575, 6048.758049677964, 6090.056041313604, 5843.474087332075, 5849.824505264849, 5854.531637468992, 5855.268091488564, 5857.912131318034, 5713.028785106542, 5830.504407125357, 5868.383819698955, 5914.0241860093465, 5914.9704251479325, 5810.302763668303, 5810.709464526757, 5813.215215945877, 5816.767329306865, 5820.330661973927, 5638.718064096779, 5823.621464604787, 5824.184614154022, 5836.795712150054, 5840.3575606683235, 4308.46144231971, 4340.305551111807, 4361.389405483234, 4390.824367025347, 4400.09797991411, 3930.744501607386, 3931.1154990127566, 3940.474946639395, 3965.20695860829, 3975.8668779398313, 5507.256520369779, 5543.833684989457, 5550.4449555044985, 5553.055980483327, 5554.250176226238, 5691.800786972436, 5826.271716035496, 5832.475077243069, 5856.4143585641095, 5906.5570082244, 5516.503514683698, 5518.034343989866, 5524.088525393346, 5525.060739674265, 5528.695921724344, 4930.827421040318, 5009.273655319958, 5014.498550144956, 5072.543471087546, 5115.155999260155, 6050.727717252734, 6110.541709371815, 6113.997365084942, 6116.694165291807, 6171.506279497629, 5926.288845338587, 5929.140274660236, 5929.258842644669, 5931.881362372815, 5980.700964951822, 5885.293764748087, 6057.212562366864, 6113.327533971348, 6139.969300153471, 6140.508759299335, 5764.13537969308, 5841.591088623755, 5843.970780146072, 5846.298444186555, 5900.852478687895, 5915.763369465345, 5969.940300596939, 5970.731317090655, 5977.7310021050025, 5978.611390259619, 5505.917411238915, 5741.799037033684, 5791.623544469596, 5816.127580862735, 5837.708114594168, 5703.857403564944, 5720.713964301685, 5730.111832665943, 5730.312362516524, 5764.596478246483, 5614.0, 5624.57815663835, 5630.155476678367, 5631.577631677558, 5633.0423827948525, 5630.831075067755, 5694.259746232963, 5699.202111704448, 5743.4256574342235, 5770.6249093808765, 5471.316085489314, 5496.5053145217125, 5497.39528651856, 5550.27846379968, 5580.2466666999435], "value": 5400.766608083178, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.20375086132, 613.1109890658545, 618.0636335951035, 624.8687775567131, 633.5691729623856, 42793.0, 43909.78045109778, 43996.56003439965, 44033.55966440339, 47567.71890529852, 44553.77723111388, 44855.1028979421, 44981.7750911245, 45053.7747311263, 45244.0, 8094.190580941889, 8600.096989816073, 8744.169303916118, 8746.0, 10513.106385957202, 10276.794464110713, 10673.412962287071, 10695.83956240653, 10696.73258168548, 10698.358098514074, 10762.623308184176, 10834.079103276192, 10836.07893329068, 10836.970487803646, 10843.783124337508, 8227.79430514239, 10598.78802423955, 10730.517126729315, 10857.0, 10867.184961127869, 10402.53188606507, 10489.265751397405, 10497.842532361945, 10499.107575855985, 10499.527521261485, 41374.37938430926, 41704.0, 41717.37423938643, 41750.37374439386, 41786.0, 3548.183917698918, 3569.3396721606587, 3626.818659067036, 3657.6708096271195, 3679.3929001714687, 32426.513602295985, 32610.67389326124, 32930.50604240938, 34214.14464638379, 34286.0, 4300.8709738707885, 4371.388005679208, 4383.890402739925, 4402.405675233835, 4430.224710675619, 336003.0, 336481.0, 336763.0, 337519.0, 338153.0, 57888.71055644779, 57891.710541447865, 58198.41801582015, 58227.12659310114, 58296.4170358291, 31976.680233197836, 32609.184770380456, 32727.50908736371, 32735.67264327326, 60666.696666516385, 16380.83619163793, 16482.093484858447, 16538.751918721227, 16547.586310342336, 16562.254698538596, 11417.714557136138, 11717.531298747954, 11814.0, 11850.0, 12060.216085954386, 19551.706724399144, 19632.70550941737, 19637.90181049114, 19770.813751174974, 19791.60416791656, 7262.993887579363, 7357.448191385667, 7456.701731930662, 7482.738104166437, 7508.061492313461, 985.7929834734786, 1006.9093781559695, 1091.406541474941, 1108.7837871614959, 1210.8910198082215, 32221.35557288841, 32435.837820811215, 32451.51322730161, 32826.83586582052, 33385.49921751176, 1120.2662256222188, 1129.1869853705318, 1132.326265871805, 1156.8380426740096, 1218.8842060004285, 17144.485665430057, 17201.397951071904, 17309.65380692379, 17437.912810435868, 17573.560660983578, 541380.0, 542363.0, 544535.0, 545526.0, 546499.0, 57455.0, 57787.422125778205, 57920.71039644775, 57972.0, 57973.420265796805, 1285.575759999198, 1356.2947267421084, 1416.3201663201514, 1419.7586410310066, 1428.550006747871, 32303.0, 32367.51448728224, 33389.83305083459, 33423.83288083544, 34066.318673625894, 49332.50667493423, 49737.75131124321, 49792.50207497879, 49863.00547978185, 49966.0, 386016.0, 386860.0, 387670.0, 387782.0, 388660.0, 24993.375165621004, 44572.0, 44731.55268447404, 45089.77455112703, 45337.54662453333, 46295.76852115718, 47138.52861471342, 50294.49705502898, 51132.23301650404, 51620.48379516157, 40237.1952560953, 41242.38136427896, 41645.375319369625, 44684.32973505335, 45946.0, 42014.0, 42323.365149522164, 42771.0, 43108.955283101415, 43392.0, 42205.36691949562, 42507.57492425036, 42662.78668606637, 43279.78360108179, 43516.0, 35936.38286277071, 37471.625283747904, 37849.81075094607, 38398.0, 38890.4166437498, 28773.568396473653, 28966.13101606955, 29019.83803251248, 29211.41577168487, 29354.945153839028], "value": 20639.91852486936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.93585026395783, 406.1207485793258, 407.1856287425148, 407.7716478771888, 421.06733585108975, 18804.717929231054, 19597.216111355545, 19626.803731962693, 19804.801951980477, 20458.181672733073, 18166.72749908749, 18574.34989775355, 19049.42851714447, 19077.42767716967, 19222.711659325123, 5324.920126198101, 5504.339479262494, 5508.7521061552325, 5570.442955704438, 5572.192032155355, 5564.805231816867, 5632.633878797886, 5639.295088113986, 5644.971775141119, 5646.887062258752, 5603.0, 5630.971845140769, 5923.052311630118, 5927.0, 6019.067044608071, 5866.911996320058, 5926.466618004379, 5931.436513531207, 5934.0, 5937.376575459558, 5617.971910140464, 5755.165501002373, 5790.826275211751, 5801.535877129862, 5806.912896306559, 13558.93220533891, 13739.656508587364, 13804.341903879726, 13996.790048149285, 13997.30013499321, 2162.826973842089, 2184.2791878680155, 2275.3628983884537, 2277.3851060213815, 2359.3865594945264, 9952.701418957442, 12832.422540985677, 13601.31993400326, 13895.722085558333, 14597.927010364987, 1804.3414153833887, 1825.7170138628535, 1914.0525439907196, 1943.038196092929, 1964.0572525187847, 77533.0, 77612.0, 77756.61121694412, 78244.26145084952, 78554.60722696407, 11028.172887033505, 11090.778184436427, 11137.610183643534, 11278.943605282086, 11432.94283528577, 15698.0, 15740.449084282223, 15818.130002849724, 15851.0, 15862.127582982816, 7809.68761249555, 8054.436189466681, 8120.796980075427, 8238.258556729923, 8328.625211865481, 7656.693732250647, 7705.807354816062, 7721.305082542598, 7808.882866757003, 7844.450888437755, 4724.95275047252, 4789.3294938708605, 4794.496577859344, 4849.781759820817, 4851.490593487633, 1976.5552750630993, 2005.1478121798177, 2023.7773844877051, 2024.6760518317294, 2040.8265297449586, 874.1040433555656, 979.118793086228, 1006.3659894266565, 1062.925595208328, 1077.6789822910278, 18425.629748100866, 18643.0, 19297.614047718966, 19564.510887227934, 19580.216791328472, 966.1015255812022, 1028.711960651019, 1052.369077306733, 1053.5575058475458, 1060.3637817309552, 10432.634857779942, 10817.675469735921, 10892.564297428173, 10942.671719848417, 10993.890061099286, 477650.0, 480484.0, 485968.0, 494934.0, 495461.0, 85159.57420212984, 88511.55744221238, 88650.0, 89994.55002725075, 90061.09938900528, 1014.9797004059877, 1038.9376637401629, 1057.9259451838298, 1062.1449732964884, 1069.684443089275, 17060.573485662957, 17091.743623845403, 17337.306507739548, 17395.95624262548, 17626.735598966283, 39749.60250397459, 40681.18637627172, 40788.59211407848, 40919.38620920629, 42228.78885605552, 56726.14910776424, 57166.14250786159, 57793.84412311646, 57819.71090144522, 59614.105788412344, 8352.540610266493, 8811.471311721318, 8814.294856411343, 8921.955390223007, 8994.370394072352, 12574.434150463434, 13299.534516291884, 13321.0, 13323.866761332263, 13431.597052088453, 11436.142289328167, 11624.256361545837, 11661.183717139718, 11808.409579521162, 11957.342346170695, 11520.0, 11595.304281743123, 11617.419129043345, 11807.586734464252, 11919.0, 11884.524619015143, 11896.345700986529, 11929.463174157358, 12091.274523528617, 12163.087768417226, 8516.0, 8677.219050285505, 8733.737987860375, 8756.649734010569, 8834.514101724468, 8860.601272942862, 11563.45982364028, 11696.298222106361, 11699.824502632637, 11796.94101529487], "value": 9163.972427801213, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.9614811555657, 1512.0398546922702, 1533.5476034569801, 1681.4787415901071, 1831.3041044403126, 8705.303575713935, 9018.09819018098, 9043.457392556451, 9322.0, 9339.953300233507, 9006.774830629238, 9231.123043310892, 9241.676541321056, 9247.768805779859, 9247.861282080758, 2034.6337659221335, 2367.6922000140044, 2405.5910495215853, 2436.5735996200683, 2442.829001969863, 2389.354874183969, 2397.856128632279, 2398.1846172301416, 2402.8798560072014, 2404.915827946022, 2336.5210131923, 2400.939976500584, 2402.531506356261, 2403.375122468153, 2406.554787364343, 2033.471297462663, 2345.765423457649, 2405.867677277749, 2410.554047501218, 2414.3360575841607, 2341.555104530134, 2342.5197834443898, 2346.2843832631042, 2346.6128088865316, 2379.892904819287, 9033.954830225806, 9183.127602877783, 9183.265338772882, 9190.126937940884, 9191.540422978824, 5321.308229930123, 5470.152126420411, 5472.151816468454, 5475.534579560742, 5480.369757477862, 3164.651888292286, 3255.788373755698, 3325.983370083134, 3374.628790833031, 3382.8985130446126, 991.9256055795844, 1023.7542989682422, 1035.9223058270584, 1045.2578669144916, 1062.6015244283394, 12842.229466232056, 12865.67835804103, 12874.742505149938, 12905.0, 12939.09426340166, 4708.745931045312, 4806.975965120152, 4861.4895435978715, 4921.188003979375, 4923.48303428142, 10767.300125491975, 10793.946030269799, 10797.352158870493, 10800.351978881292, 10808.459577021118, 4877.292792545061, 5443.129099344165, 5456.072467680504, 5459.69971651563, 5463.781448742086, 4872.43966943806, 4995.975020124876, 5009.949900500948, 5050.1667224908215, 5064.620153488503, 4098.590140985937, 4160.83356665736, 4163.604457576525, 4199.307114326163, 4209.389638502461, 1285.8971282297393, 1316.2290039038278, 1325.0790700463278, 1326.3633455941201, 1337.2444568818587, 957.8802649668792, 965.584798536636, 973.8101070291225, 976.7069879036261, 991.311038828022, 5808.30300363959, 5893.557983151195, 5936.376680448577, 6027.156198132265, 6132.724027418777, 901.5717534171216, 931.8602209668604, 950.6435086842434, 958.5925981457852, 1016.0753714120262, 3773.9245215095543, 3828.7511311764647, 3875.050612599931, 3946.7039972002217, 3963.564007959122, 11112.499937502831, 11255.43722813856, 11423.828642570368, 11494.195406321645, 11512.366819824823, 11160.609378671708, 11305.0, 11317.207795454238, 11388.829167562493, 11398.316101033965, 8257.95871020641, 8674.349423793241, 8735.868961965576, 8759.080296568896, 8773.341999349946, 5628.352739434854, 5667.716614169358, 5738.22533957927, 5792.275965504312, 5834.679092649945, 10634.30876992993, 10784.299020563638, 10791.6222932197, 10802.0, 10806.459677016275, 10513.737156571147, 10877.238593298393, 10930.508127134139, 11005.0, 11016.779664406828, 6424.550281480251, 7025.613591252531, 7185.06594142779, 7250.818729531804, 7368.005319282042, 8734.0, 8796.164364385373, 8820.823583528421, 8860.601272942604, 8881.911180888366, 7917.683292668229, 8092.919070809452, 8108.607222180676, 8214.0, 8214.260716535302, 7892.36861051126, 7934.761957141295, 7952.966114405091, 8053.436259461781, 8084.514929104273, 6673.399394054559, 6730.495212859153, 6753.1220941277315, 6805.625690587065, 6822.0108084328485, 4933.753312334441, 5002.899942001212, 5013.448520662797, 5013.974930125326, 5077.720725360141, 5098.719570423662, 5113.3097031900215, 5167.22491626244, 5191.766370513412, 5344.144936810092], "value": 4719.0301566650805, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.855165175671, 335.2875140326806, 336.76931302058074, 338.6393490932157, 340.82788191963056, 5128.461511541285, 5245.554127899129, 5305.071612467822, 5337.6797392156495, 5377.731113444327, 5239.790408383669, 5285.867853303669, 5297.496737809912, 5307.20391941209, 5314.813981510638, 4164.875053748392, 4183.309753890604, 4207.663386929054, 4213.978930105361, 4214.97892510537, 4033.9596604034023, 4164.75011499311, 4180.352045432947, 4189.162167566484, 4223.239816832969, 4126.834926602933, 4142.212979533879, 4143.523494798092, 4150.5265630701115, 4178.0, 4151.14901445206, 4156.875293741193, 4187.895302617444, 4190.916181676349, 4203.684723645739, 3661.6887564557046, 3725.8137093145233, 3733.831977561016, 3743.3262012837677, 3743.850245990157, 5074.188129899198, 5076.187809950427, 5079.771410286546, 5085.694858308476, 5104.183330667075, 4721.0, 4728.905421891577, 4728.976355118202, 4733.390647179941, 4745.359376484165, 5256.684598924076, 5400.459954004607, 5403.838174792439, 5410.702411367373, 5415.566754659657, 4033.193361327746, 4038.8788336349958, 4041.2321658884716, 4046.5953404659595, 4055.3511438170044, 5451.754671039813, 5562.413477732874, 5758.021136406821, 5813.651180929115, 5823.883522329572, 4090.570490098497, 4093.2427500912427, 4126.154138401621, 4131.235721391552, 4147.771872546979, 4164.6251837334785, 4166.812493437803, 4172.874813755592, 4183.372494125844, 4191.3293872980785, 4529.162105010583, 4639.512851150581, 4707.270373092176, 4714.5992590630185, 4744.4069491313585, 5464.23500709901, 5475.123980163155, 5477.7261136943, 5486.670799752028, 5496.395396506381, 1428.057482061855, 1444.9885080443732, 1454.3600815641296, 1461.8903582231376, 1465.1648560320727, 918.830016446946, 935.1770442010986, 938.15565990609, 954.725441535544, 971.2569884038716, 4675.322078298627, 4704.553067458586, 4705.905881882343, 4740.715557066587, 4759.286107083896, 4974.801007959713, 5004.149294619923, 5016.2224855147515, 5017.698938063655, 5019.623528235397, 4720.645951553647, 4727.54167832725, 4736.526347365236, 4771.66598338113, 4928.186849169847, 3026.243439140215, 3034.893778717734, 3050.9847450762604, 3052.8168309901475, 3059.143439836849, 5961.314448838352, 6000.0, 6007.519398448114, 6015.0977353396465, 6022.518198544106, 5655.0, 5870.589058765845, 5881.852953676193, 5883.264591926009, 5886.3230728465915, 5861.17943487912, 5884.175905469318, 5913.70431478433, 5930.495907847795, 5988.580799344091, 5557.472040156179, 5609.663420194802, 5734.426557344233, 5737.799177028784, 5750.568707347049, 5328.57371410294, 5621.184928185308, 5630.971845140748, 5636.182753500638, 5654.660720356792, 5763.337216220189, 5891.734871930859, 5891.970540147443, 5904.586678932434, 5929.636183677692, 3996.160806230724, 4012.53855806586, 4019.93970090443, 4027.134166154291, 4057.7159598827798, 5407.648502847303, 5639.0, 5646.237757902795, 5751.8562035949435, 5761.798337058183, 5573.721313934206, 5584.162375643686, 5608.046632072639, 5629.155626656035, 5646.463585959327, 5545.7781688732, 5555.805546805843, 5571.0, 5610.0, 5613.410591887793, 5165.9483405166975, 5454.527277634937, 5467.808626698046, 5470.945290547043, 5487.890242195213, 5552.416996215339, 5596.776128954796, 5598.860028499157, 5606.159076138611, 5607.523360514238, 5181.248718935809, 5433.0169488137335, 5463.448758307132, 5486.807961721321, 5499.3675727291875], "value": 4207.0292219276, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.3726708695089, 700.2227527444536, 712.6828561290224, 810.8256724804168, 879.2702057292446, 14594.63513412164, 15059.924700376496, 15064.774028389584, 15317.69364612707, 15330.463433779805, 14673.413063477448, 15089.0, 15132.394704211845, 15148.848511514909, 15161.469348572802, 690.1028662738445, 815.6941147069834, 830.2818062376061, 830.9916900831005, 835.9498430094184, 805.842860642175, 834.995825020874, 836.0510820219065, 841.6338892581716, 841.9957900210491, 868.7306934850185, 868.9869651955194, 869.5087275689247, 869.9565021748919, 871.337783284705, 720.6504845150098, 843.696269343036, 866.5017614871442, 866.9046404895456, 867.2584939876378, 626.2234828812285, 630.4546567219368, 630.9022101574263, 631.8546734251101, 631.962082275065, 15021.774673379909, 15032.699346013018, 15057.774133388008, 15073.095614263179, 15086.471973480875, 13049.151805132637, 13052.412641431158, 13219.86780132196, 13257.270850103241, 14191.148531088167, 12425.31660758658, 12469.937650311782, 12540.0, 12545.372731363395, 12709.491620335268, 4368.956310436918, 4392.824287028515, 4468.4414448193975, 4497.302918047708, 4501.41481607389, 72951.0, 73442.0, 73638.63180684116, 73984.0, 74169.25830741678, 10943.726406839893, 11077.72305692348, 11249.66251012471, 11313.208075434804, 11407.0, 21167.78832211689, 21183.78816211849, 21187.15251389958, 21208.15167393318, 21224.25715099964, 8531.402801803939, 8570.314374849997, 8624.482531048157, 8680.56597170139, 8909.465432074096, 4615.26155815068, 4661.883452913704, 4690.929636055462, 4696.647751418657, 4899.926501102486, 3273.688999545087, 3379.3579219948133, 3383.3571621391857, 3386.4581666933173, 3389.305192435545, 577.1314172170856, 577.7602295047508, 579.7101449275362, 594.6996766632909, 602.2892986276148, 517.1292872846814, 536.407269966689, 542.6798189068469, 613.4999975020344, 615.5198944823045, 9098.772530686654, 9258.814823703487, 9301.209397201314, 9381.249500040118, 9532.18976387001, 600.7206648908278, 613.1293563140351, 624.4660815003198, 644.3893938867411, 662.9171353580803, 4384.868453946387, 4395.494518130392, 4413.757243351646, 4643.396358473445, 4657.930131048037, 64434.67782661151, 65669.0, 65839.67080164664, 65923.67038164778, 98769.50615246878, 91178.0, 91181.54409227912, 92085.53957230171, 92880.0, 92966.0, 80515.0, 81176.18823811805, 81434.59282703549, 81551.59224203842, 82504.17495825085, 8671.739847804576, 8786.25316848075, 8790.340724445565, 8876.245519130945, 9303.86044209351, 49734.75132624314, 49994.75002624963, 51027.2345914819, 51040.23439648334, 52203.477965221384, 44714.0, 51313.0, 73492.0, 73799.63100184465, 74344.0, 11619.670955305819, 11899.226550274205, 11949.0, 11952.0, 12074.39628018613, 12458.127931044739, 12517.749645006867, 13003.414846332127, 13023.218606883618, 13352.465901363837, 12099.516019359127, 12499.250044997329, 12522.186057906207, 12702.30137342455, 12795.603281637232, 11254.662360129209, 11290.661280161608, 11551.306921584732, 11626.825597615873, 11639.301641901513, 11428.714282143013, 11762.58830940913, 11868.584599538975, 11869.821952670543, 11870.169088164092, 9061.501617410842, 9192.126747958933, 9491.288153388661, 9492.382995105296, 9872.160866326443, 9581.712548623553, 10082.495875206358, 10091.404025773056, 10138.79722405533, 10151.796964060824], "value": 7112.030774733848, "unit": "score"}], "name": "dromaeo_css", "value": 7241.89797566582, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 112.0, 111.0, 111.0, 113.0, 111.0, 111.0, 111.0, 110.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 126.0, 127.0, 191.0, 126.0, 125.0, 148.0, 124.0, 157.0, 125.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 159.0, 161.0, 160.0, 161.0, 160.0, 162.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 122.0, 76.0, 76.0, 73.0, 74.0, 97.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 113.0, 84.0, 85.0, 87.0, 122.0, 93.0, 85.0, 122.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 147.0, 145.0, 144.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 96.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 52.0, 54.0, 56.0, 55.0, 54.0, 55.0, 55.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 64.0, 60.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 83.0, 79.0, 93.0, 85.0, 81.0, 80.0, 112.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 131.0, 124.0, 132.0, 125.0, 127.0, 121.0, 122.0, 144.0, 122.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 160.0, 158.0, 160.0, 160.0, 159.0, 160.0, 159.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 65.0, 66.0, 63.0, 62.0, 62.0, 71.0, 63.0, 63.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1502.3000000000002, "alertThreshold": 2.0}]} 19:24:05 INFO - Return code: 0 19:24:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:24:05 ERROR - # TBPL SUCCESS # 19:24:05 INFO - Running post-action listener: _resource_record_post_action 19:24:05 INFO - ##### 19:24:05 INFO - ##### Finished run-tests step (success) 19:24:05 INFO - ##### 19:24:05 INFO - Running post-run listener: _resource_record_post_run 19:24:05 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 503808; Write bytes: 312741888; Read time: 216; Write time: 230016 19:24:05 INFO - TinderboxPrint: CPU usage
13.1% 19:24:05 INFO - TinderboxPrint: I/O read bytes / time
503,808 / 216 19:24:05 INFO - TinderboxPrint: I/O write bytes / time
312,741,888 / 230,016 19:24:05 INFO - TinderboxPrint: CPU idle
8,255.7 (86.8%) 19:24:05 INFO - TinderboxPrint: CPU user
1,209.2 (12.7%) 19:24:05 INFO - TinderboxPrint: Swap in / out
0 / 0 19:24:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 20107264; Read time: 0; Write time: 8268 19:24:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 503808; Write bytes: 292634624; Read time: 216; Write time: 221748 19:24:05 INFO - Running post-run listener: _upload_blobber_files 19:24:05 INFO - Blob upload gear active. 19:24:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:24:05 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 19:24:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:24:06 INFO - (blobuploader) - INFO - Open directory for files ... 19:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:24:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:06 INFO - (blobuploader) - INFO - Done attempting. 19:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:24:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:24:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:11 INFO - (blobuploader) - INFO - Done attempting. 19:24:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:24:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:24:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:11 INFO - (blobuploader) - INFO - Done attempting. 19:24:11 INFO - (blobuploader) - INFO - Iteration through files over. 19:24:11 INFO - Return code: 0 19:24:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:24:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:24:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2b49c82cc96ff91dff243076c836fc3b31fc0282ee2284d6feb83a2135845d09cc15c40234c2d0734324ac2a488daae72208f1afad7529946749b156fa78edf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12fb691e1bf7881d388b87194a47f1c2df8157df63d7a5882ce4f89a8e10fd00131c160ef95a3b5cfa7df10db0b5c989b849ab0ca48ef64bb413e0234b25aab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/474c7ccfb74366260b6d4659cdfb3a0301901c071590238877d335e7ad22881fe5d8728335acc0c74d5a1259f7d720460ad01c11dcd1f5fa643e7fbd51a75d63"} 19:24:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:24:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:24:11 INFO - Contents: 19:24:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2b49c82cc96ff91dff243076c836fc3b31fc0282ee2284d6feb83a2135845d09cc15c40234c2d0734324ac2a488daae72208f1afad7529946749b156fa78edf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12fb691e1bf7881d388b87194a47f1c2df8157df63d7a5882ce4f89a8e10fd00131c160ef95a3b5cfa7df10db0b5c989b849ab0ca48ef64bb413e0234b25aab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/474c7ccfb74366260b6d4659cdfb3a0301901c071590238877d335e7ad22881fe5d8728335acc0c74d5a1259f7d720460ad01c11dcd1f5fa643e7fbd51a75d63"} 19:24:11 INFO - Running post-run listener: copy_logs_to_upload_dir 19:24:11 INFO - Copying logs to upload dir... 19:24:11 INFO - mkdir: /builds/slave/test/build/upload/logs 19:24:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1351.738133 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 31 secs) (at 2016-06-05 19:24:11.716382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 19:24:11.720278) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2b49c82cc96ff91dff243076c836fc3b31fc0282ee2284d6feb83a2135845d09cc15c40234c2d0734324ac2a488daae72208f1afad7529946749b156fa78edf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12fb691e1bf7881d388b87194a47f1c2df8157df63d7a5882ce4f89a8e10fd00131c160ef95a3b5cfa7df10db0b5c989b849ab0ca48ef64bb413e0234b25aab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/474c7ccfb74366260b6d4659cdfb3a0301901c071590238877d335e7ad22881fe5d8728335acc0c74d5a1259f7d720460ad01c11dcd1f5fa643e7fbd51a75d63"} build_url:https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011159 build_url: 'https://queue.taskcluster.net/v1/task/WYW6B_w2T2aNyGUZQXFGaw/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/Mozilla-Inbound/sha512/2b49c82cc96ff91dff243076c836fc3b31fc0282ee2284d6feb83a2135845d09cc15c40234c2d0734324ac2a488daae72208f1afad7529946749b156fa78edf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12fb691e1bf7881d388b87194a47f1c2df8157df63d7a5882ce4f89a8e10fd00131c160ef95a3b5cfa7df10db0b5c989b849ab0ca48ef64bb413e0234b25aab3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/474c7ccfb74366260b6d4659cdfb3a0301901c071590238877d335e7ad22881fe5d8728335acc0c74d5a1259f7d720460ad01c11dcd1f5fa643e7fbd51a75d63"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 19:24:11.748051) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:24:11.748602) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1465169829.646773-1891298998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 19:24:11.829690) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 19:24:11.830031) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 19:24:11.830334) ========= ========= Total master_lag: 0.18 =========