builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1470226616.83 results: success (0) buildid: 20160803022841 builduid: 901ac805f8da4ed19af554710d33a2f8 revision: 781e498746664fbff97f752ba37065f98eded8c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:56.826806) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:56.827223) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:56.827563) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:56.870287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:56.870579) ========= 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=0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-03 05:16:56-- 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.6M=0s 2016-08-03 05:16:57 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140942 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.030553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.030853) ========= 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=0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017376 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.088181) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.088565) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 781e498746664fbff97f752ba37065f98eded8c2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 781e498746664fbff97f752ba37065f98eded8c2 --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=0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-03 05:16:57,150 truncating revision to first 12 chars 2016-08-03 05:16:57,150 Setting DEBUG logging. 2016-08-03 05:16:57,150 attempt 1/10 2016-08-03 05:16:57,150 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/781e49874666?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-03 05:16:57,455 unpacking tar archive at: mozilla-beta-781e49874666/testing/mozharness/ program finished with exit code 0 elapsedTime=0.635883 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.739076) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.739386) ========= script_repo_revision: 781e498746664fbff97f752ba37065f98eded8c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.739790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.740056) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 05:16:57.748507) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-03 05:16:57.748784) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:16:57 INFO - MultiFileLogger online at 20160803 05:16:57 in /builds/slave/test 05:16:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:16:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:16:57 INFO - {'append_to_log': False, 05:16:57 INFO - 'base_work_dir': '/builds/slave/test', 05:16:57 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:16:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:16:57 INFO - 'branch': 'Mozilla-Beta', 05:16:57 INFO - 'buildbot_json_path': 'buildprops.json', 05:16:57 INFO - 'config_files': ('talos/linux_config.py',), 05:16:57 INFO - 'default_actions': ('clobber', 05:16:57 INFO - 'read-buildbot-config', 05:16:57 INFO - 'download-and-extract', 05:16:57 INFO - 'populate-webroot', 05:16:57 INFO - 'create-virtualenv', 05:16:57 INFO - 'install', 05:16:57 INFO - 'run-tests'), 05:16:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:16:57 INFO - 'download_minidump_stackwalk': True, 05:16:57 INFO - 'download_symbols': 'ondemand', 05:16:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:16:57 INFO - 'tooltool.py': '/tools/tooltool.py', 05:16:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:16:57 INFO - '/tools/misc-python/virtualenv.py')}, 05:16:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:16:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:16:57 INFO - 'installer_path': 'installer.exe', 05:16:57 INFO - 'log_level': 'info', 05:16:57 INFO - 'log_name': 'talos', 05:16:57 INFO - 'log_to_console': True, 05:16:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:16:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:16:57 INFO - 'opt_config_files': (), 05:16:57 INFO - 'pip_index': False, 05:16:57 INFO - 'sps_profile': False, 05:16:57 INFO - 'sps_profile_interval': 0, 05:16:57 INFO - 'suite': 'dromaeojs', 05:16:57 INFO - 'system_bits': '32', 05:16:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:16:57 INFO - 'title': 'talos-linux64-ix-058', 05:16:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:16:57 INFO - 'use_talos_json': True, 05:16:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:16:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:16:57 INFO - 'work_dir': 'build'} 05:16:57 INFO - ##### 05:16:57 INFO - ##### Running clobber step. 05:16:57 INFO - ##### 05:16:57 INFO - Running pre-action listener: _resource_record_pre_action 05:16:57 INFO - Running main action method: clobber 05:16:57 INFO - rmtree: /builds/slave/test/build 05:16:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:16:58 INFO - Running post-action listener: _resource_record_post_action 05:16:58 INFO - ##### 05:16:58 INFO - ##### Finished clobber step (success) 05:16:58 INFO - ##### 05:16:58 INFO - ##### 05:16:58 INFO - ##### Running read-buildbot-config step. 05:16:58 INFO - ##### 05:16:58 INFO - Running pre-action listener: _resource_record_pre_action 05:16:58 INFO - Running main action method: read_buildbot_config 05:16:58 INFO - Using buildbot properties: 05:16:58 INFO - { 05:16:58 INFO - "project": "", 05:16:58 INFO - "product": "firefox", 05:16:58 INFO - "script_repo_revision": "production", 05:16:58 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:16:58 INFO - "repository": "", 05:16:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:16:58 INFO - "buildid": "20160803022841", 05:16:58 INFO - "pgo_build": "True", 05:16:58 INFO - "basedir": "/builds/slave/test", 05:16:58 INFO - "buildnumber": 324, 05:16:58 INFO - "slavename": "talos-linux64-ix-058", 05:16:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:16:58 INFO - "platform": "ubuntu64_hw", 05:16:58 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:16:58 INFO - "branch": "mozilla-beta", 05:16:58 INFO - "repo_path": "releases/mozilla-beta", 05:16:58 INFO - "revision": "781e498746664fbff97f752ba37065f98eded8c2", 05:16:58 INFO - "stage_platform": "linux64-pgo", 05:16:58 INFO - "builduid": "901ac805f8da4ed19af554710d33a2f8", 05:16:58 INFO - "slavebuilddir": "test" 05:16:58 INFO - } 05:16:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 05:16:58 INFO - Running post-action listener: _resource_record_post_action 05:16:58 INFO - ##### 05:16:58 INFO - ##### Finished read-buildbot-config step (success) 05:16:58 INFO - ##### 05:16:58 INFO - ##### 05:16:58 INFO - ##### Running download-and-extract step. 05:16:58 INFO - ##### 05:16:58 INFO - Running pre-action listener: _resource_record_pre_action 05:16:58 INFO - Running main action method: download_and_extract 05:16:58 INFO - mkdir: /builds/slave/test/build/tests 05:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:58 INFO - https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:16:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 05:16:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 05:16:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 05:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:16:59 INFO - Downloaded 1327 bytes. 05:16:59 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 05:16:59 INFO - Using the following test package requirements: 05:16:59 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 05:16:59 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:16:59 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 05:16:59 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'jsshell-linux-x86_64.zip'], 05:16:59 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:16:59 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 05:16:59 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 05:16:59 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 05:16:59 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:16:59 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 05:16:59 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:16:59 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:16: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')]} 05:16:59 INFO - https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:16:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 05:16:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 05:16:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 05:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:17:00 INFO - Downloaded 21967347 bytes. 05:17:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:17:01 INFO - Return code: 0 05:17:01 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:01 INFO - https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:17:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 05:17:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 05:17:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 05:17:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:17:09 INFO - Downloaded 21967347 bytes. 05:17:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:17:10 INFO - Return code: 0 05:17: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')]} 05:17:10 INFO - https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:17:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 05:17:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 05:17:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 05:17:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:17:11 INFO - Downloaded 10833369 bytes. 05:17:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:17:11 INFO - Return code: 0 05:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:11 INFO - https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:17:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 05:17:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 05:17:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 05:17:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:17:12 INFO - Downloaded 55281215 bytes. 05:17:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 05:17:12 INFO - mkdir: /builds/slave/test/properties 05:17:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:12 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:12 INFO - Contents: 05:17:12 INFO - build_url:https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 05:17:13 INFO - Running post-action listener: _resource_record_post_action 05:17:13 INFO - Running post-action listener: set_extra_try_arguments 05:17:13 INFO - ##### 05:17:13 INFO - ##### Finished download-and-extract step (success) 05:17:13 INFO - ##### 05:17:13 INFO - ##### 05:17:13 INFO - ##### Running populate-webroot step. 05:17:13 INFO - ##### 05:17:13 INFO - Running pre-action listener: _resource_record_pre_action 05:17:13 INFO - Running main action method: populate_webroot 05:17:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:13 INFO - '--tppagecycles', 05:17:13 INFO - '7'], 05:17:13 INFO - 'tests': ['tsvgm']}}, 05:17:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['damp', 'tps']}, 05:17:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'tests': ['damp', 'tps']}, 05:17:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['dromaeo_dom']}, 05:17:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['a11yr', 05:17:13 INFO - 'ts_paint', 05:17:13 INFO - 'tpaint', 05:17:13 INFO - 'sessionrestore', 05:17:13 INFO - 'sessionrestore_no_auto_restore', 05:17:13 INFO - 'tabpaint']}, 05:17:13 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:13 INFO - 'ts_paint', 05:17:13 INFO - 'tpaint', 05:17:13 INFO - 'sessionrestore', 05:17:13 INFO - 'sessionrestore_no_auto_restore', 05:17:13 INFO - 'tabpaint']}, 05:17:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:13 INFO - 'ts_paint', 05:17:13 INFO - 'tpaint', 05:17:13 INFO - 'sessionrestore', 05:17:13 INFO - 'sessionrestore_no_auto_restore', 05:17:13 INFO - 'tabpaint']}, 05:17:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:13 INFO - 'ts_paint', 05:17:13 INFO - 'tpaint', 05:17:13 INFO - 'sessionrestore', 05:17:13 INFO - 'sessionrestore_no_auto_restore', 05:17:13 INFO - 'tabpaint']}, 05:17:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['a11yr', 05:17:13 INFO - 'ts_paint', 05:17:13 INFO - 'tpaint', 05:17:13 INFO - 'sessionrestore', 05:17:13 INFO - 'sessionrestore_no_auto_restore', 05:17:13 INFO - 'tabpaint']}, 05:17:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['a11yr', 05:17:13 INFO - 'ts_paint', 05:17:13 INFO - 'tpaint', 05:17:13 INFO - 'sessionrestore', 05:17:13 INFO - 'sessionrestore_no_auto_restore', 05:17:13 INFO - 'tabpaint']}, 05:17:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['tsvgx', 05:17:13 INFO - 'tsvgr_opacity', 05:17:13 INFO - 'tart', 05:17:13 INFO - 'tscrollx', 05:17:13 INFO - 'cart']}, 05:17:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:13 INFO - 'tsvgr_opacity', 05:17:13 INFO - 'tart', 05:17:13 INFO - 'tscrollx', 05:17:13 INFO - 'cart']}, 05:17:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'talos_options': ['--disable-e10s'], 05:17:13 INFO - 'tests': ['tp5o']}, 05:17:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'tests': ['tp5o']}, 05:17:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'talos_options': ['--disable-e10s', 05:17:13 INFO - '--xperf_path', 05:17:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:13 INFO - 'tests': ['tp5n']}, 05:17:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:13 INFO - 'talos_options': ['--xperf_path', 05:17:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:13 INFO - 'tests': ['tp5n']}}, 05:17:13 INFO - 'talos.zip': {'path': '', 05:17:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:13 INFO - Running post-action listener: _resource_record_post_action 05:17:13 INFO - ##### 05:17:13 INFO - ##### Finished populate-webroot step (success) 05:17:13 INFO - ##### 05:17:13 INFO - ##### 05:17:13 INFO - ##### Running create-virtualenv step. 05:17:13 INFO - ##### 05:17:13 INFO - Running pre-action listener: _resource_record_pre_action 05:17:13 INFO - Running main action method: create_virtualenv 05:17:13 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:17:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:13 INFO - Using real prefix '/usr' 05:17:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:14 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:16 INFO - Installing pip.................done. 05:17:16 INFO - Return code: 0 05:17:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:16 INFO - Using env: {'DISPLAY': ':0', 05:17:16 INFO - 'HOME': '/home/cltbld', 05:17:16 INFO - 'LANG': 'en_US.UTF-8', 05:17:16 INFO - 'LANGUAGE': 'en_US:en', 05:17:16 INFO - 'LOGNAME': 'cltbld', 05:17:16 INFO - 'MAIL': '/var/mail/cltbld', 05:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:16 INFO - 'MOZ_NO_REMOTE': '1', 05:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:16 INFO - 'NO_EM_RESTART': '1', 05:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:16 INFO - 'PWD': '/builds/slave/test', 05:17:16 INFO - 'SHELL': '/bin/bash', 05:17:16 INFO - 'SHLVL': '1', 05:17:16 INFO - 'TERM': 'linux', 05:17:16 INFO - 'TMOUT': '86400', 05:17:16 INFO - 'USER': 'cltbld', 05:17:16 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:16 INFO - '_': '/tools/buildbot/bin/python'} 05:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:17:16 INFO - Cleaning up... 05:17:16 INFO - Return code: 0 05:17:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:17:16 INFO - Using env: {'DISPLAY': ':0', 05:17:16 INFO - 'HOME': '/home/cltbld', 05:17:16 INFO - 'LANG': 'en_US.UTF-8', 05:17:16 INFO - 'LANGUAGE': 'en_US:en', 05:17:16 INFO - 'LOGNAME': 'cltbld', 05:17:16 INFO - 'MAIL': '/var/mail/cltbld', 05:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:16 INFO - 'MOZ_NO_REMOTE': '1', 05:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:16 INFO - 'NO_EM_RESTART': '1', 05:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:16 INFO - 'PWD': '/builds/slave/test', 05:17:16 INFO - 'SHELL': '/bin/bash', 05:17:16 INFO - 'SHLVL': '1', 05:17:16 INFO - 'TERM': 'linux', 05:17:16 INFO - 'TMOUT': '86400', 05:17:16 INFO - 'USER': 'cltbld', 05:17:16 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:16 INFO - '_': '/tools/buildbot/bin/python'} 05:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:16 INFO - Downloading/unpacking psutil>=3.1.1 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:18 INFO - Installing collected packages: psutil 05:17:18 INFO - Running setup.py install for psutil 05:17:18 INFO - building 'psutil._psutil_linux' extension 05:17:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:17:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:17:18 INFO - building 'psutil._psutil_posix' extension 05:17:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:17:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:17:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:19 INFO - Successfully installed psutil 05:17:19 INFO - Cleaning up... 05:17:19 INFO - Return code: 0 05:17:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:17:19 INFO - Using env: {'DISPLAY': ':0', 05:17:19 INFO - 'HOME': '/home/cltbld', 05:17:19 INFO - 'LANG': 'en_US.UTF-8', 05:17:19 INFO - 'LANGUAGE': 'en_US:en', 05:17:19 INFO - 'LOGNAME': 'cltbld', 05:17:19 INFO - 'MAIL': '/var/mail/cltbld', 05:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:19 INFO - 'MOZ_NO_REMOTE': '1', 05:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:19 INFO - 'NO_EM_RESTART': '1', 05:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:19 INFO - 'PWD': '/builds/slave/test', 05:17:19 INFO - 'SHELL': '/bin/bash', 05:17:19 INFO - 'SHLVL': '1', 05:17:19 INFO - 'TERM': 'linux', 05:17:19 INFO - 'TMOUT': '86400', 05:17:19 INFO - 'USER': 'cltbld', 05:17:19 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:19 INFO - '_': '/tools/buildbot/bin/python'} 05:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:17:21 INFO - Installing collected packages: mozsystemmonitor 05:17:21 INFO - Running setup.py install for mozsystemmonitor 05:17:21 INFO - Successfully installed mozsystemmonitor 05:17:21 INFO - Cleaning up... 05:17:21 INFO - Return code: 0 05:17:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:17:21 INFO - Using env: {'DISPLAY': ':0', 05:17:21 INFO - 'HOME': '/home/cltbld', 05:17:21 INFO - 'LANG': 'en_US.UTF-8', 05:17:21 INFO - 'LANGUAGE': 'en_US:en', 05:17:21 INFO - 'LOGNAME': 'cltbld', 05:17:21 INFO - 'MAIL': '/var/mail/cltbld', 05:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:21 INFO - 'MOZ_NO_REMOTE': '1', 05:17:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:21 INFO - 'NO_EM_RESTART': '1', 05:17:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:21 INFO - 'PWD': '/builds/slave/test', 05:17:21 INFO - 'SHELL': '/bin/bash', 05:17:21 INFO - 'SHLVL': '1', 05:17:21 INFO - 'TERM': 'linux', 05:17:21 INFO - 'TMOUT': '86400', 05:17:21 INFO - 'USER': 'cltbld', 05:17:21 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:21 INFO - '_': '/tools/buildbot/bin/python'} 05:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:21 INFO - Downloading/unpacking jsonschema==2.5.1 05:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:17:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:23 INFO - Downloading functools32-3.2.3-2.tar.gz 05:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:17:23 INFO - warning: no files found matching '*.txt' 05:17:23 INFO - no previously-included directories found matching 'build' 05:17:23 INFO - no previously-included directories found matching 'dist' 05:17:23 INFO - no previously-included directories found matching '.git*' 05:17:23 INFO - Installing collected packages: jsonschema, functools32 05:17:23 INFO - Running setup.py install for functools32 05:17:24 INFO - warning: no files found matching '*.txt' 05:17:24 INFO - no previously-included directories found matching 'build' 05:17:24 INFO - no previously-included directories found matching 'dist' 05:17:24 INFO - no previously-included directories found matching '.git*' 05:17:24 INFO - Successfully installed jsonschema functools32 05:17:24 INFO - Cleaning up... 05:17:24 INFO - Return code: 0 05:17:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:24 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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:17:24 INFO - Using env: {'DISPLAY': ':0', 05:17:24 INFO - 'HOME': '/home/cltbld', 05:17:24 INFO - 'LANG': 'en_US.UTF-8', 05:17:24 INFO - 'LANGUAGE': 'en_US:en', 05:17:24 INFO - 'LOGNAME': 'cltbld', 05:17:24 INFO - 'MAIL': '/var/mail/cltbld', 05:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:24 INFO - 'MOZ_NO_REMOTE': '1', 05:17:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:24 INFO - 'NO_EM_RESTART': '1', 05:17:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:24 INFO - 'PWD': '/builds/slave/test', 05:17:24 INFO - 'SHELL': '/bin/bash', 05:17:24 INFO - 'SHLVL': '1', 05:17:24 INFO - 'TERM': 'linux', 05:17:24 INFO - 'TMOUT': '86400', 05:17:24 INFO - 'USER': 'cltbld', 05:17:24 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:24 INFO - '_': '/tools/buildbot/bin/python'} 05:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:24 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:26 INFO - Downloading blobuploader-1.2.4.tar.gz 05:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:17:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:17:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:26 INFO - Downloading docopt-0.6.1.tar.gz 05:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:17:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:17:26 INFO - Running setup.py install for blobuploader 05:17:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:17:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:17:27 INFO - Running setup.py install for requests 05:17:27 INFO - Running setup.py install for docopt 05:17:27 INFO - Successfully installed blobuploader requests docopt 05:17:27 INFO - Cleaning up... 05:17:27 INFO - Return code: 0 05:17:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:27 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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:17:27 INFO - Using env: {'DISPLAY': ':0', 05:17:27 INFO - 'HOME': '/home/cltbld', 05:17:27 INFO - 'LANG': 'en_US.UTF-8', 05:17:27 INFO - 'LANGUAGE': 'en_US:en', 05:17:27 INFO - 'LOGNAME': 'cltbld', 05:17:27 INFO - 'MAIL': '/var/mail/cltbld', 05:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:27 INFO - 'MOZ_NO_REMOTE': '1', 05:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:27 INFO - 'NO_EM_RESTART': '1', 05:17:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:27 INFO - 'PWD': '/builds/slave/test', 05:17:27 INFO - 'SHELL': '/bin/bash', 05:17:27 INFO - 'SHLVL': '1', 05:17:27 INFO - 'TERM': 'linux', 05:17:27 INFO - 'TMOUT': '86400', 05:17:27 INFO - 'USER': 'cltbld', 05:17:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:27 INFO - '_': '/tools/buildbot/bin/python'} 05:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:27 INFO - Running setup.py (path:/tmp/pip-z3hLzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:27 INFO - Running setup.py (path:/tmp/pip-E4OBj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:27 INFO - Running setup.py (path:/tmp/pip-Y6mdNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:28 INFO - Running setup.py (path:/tmp/pip-NVte3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:28 INFO - Running setup.py (path:/tmp/pip-LjhqCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:28 INFO - Running setup.py (path:/tmp/pip-2Mheuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:28 INFO - Running setup.py (path:/tmp/pip-LjcNJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:28 INFO - Running setup.py (path:/tmp/pip-1J1T4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:28 INFO - Running setup.py (path:/tmp/pip-GJzE10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:28 INFO - Running setup.py (path:/tmp/pip-p3TKiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:28 INFO - Running setup.py (path:/tmp/pip-8KYVx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:28 INFO - Running setup.py (path:/tmp/pip-eYHNGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:28 INFO - Running setup.py (path:/tmp/pip-rg70ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:28 INFO - Running setup.py (path:/tmp/pip-PZsOE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:28 INFO - Running setup.py (path:/tmp/pip-kxdXtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:28 INFO - Running setup.py (path:/tmp/pip-JB6ScM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:28 INFO - Running setup.py (path:/tmp/pip-rIwHF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:17:29 INFO - Running setup.py install for manifestparser 05:17:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozcrash 05:17:29 INFO - Running setup.py install for mozdebug 05:17:29 INFO - Running setup.py install for mozdevice 05:17:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozfile 05:17:29 INFO - Running setup.py install for mozhttpd 05:17:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozinfo 05:17:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozInstall 05:17:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozleak 05:17:29 INFO - Running setup.py install for mozlog 05:17:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Running setup.py install for moznetwork 05:17:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Running setup.py install for mozprocess 05:17:30 INFO - Running setup.py install for mozprofile 05:17:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Running setup.py install for mozrunner 05:17:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Running setup.py install for mozscreenshot 05:17:30 INFO - Running setup.py install for moztest 05:17:30 INFO - Running setup.py install for mozversion 05:17:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:17:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:30 INFO - Cleaning up... 05:17:30 INFO - Return code: 0 05:17:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:17:30 INFO - Using env: {'DISPLAY': ':0', 05:17:30 INFO - 'HOME': '/home/cltbld', 05:17:30 INFO - 'LANG': 'en_US.UTF-8', 05:17:30 INFO - 'LANGUAGE': 'en_US:en', 05:17:30 INFO - 'LOGNAME': 'cltbld', 05:17:30 INFO - 'MAIL': '/var/mail/cltbld', 05:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:30 INFO - 'MOZ_NO_REMOTE': '1', 05:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:30 INFO - 'NO_EM_RESTART': '1', 05:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:30 INFO - 'PWD': '/builds/slave/test', 05:17:30 INFO - 'SHELL': '/bin/bash', 05:17:30 INFO - 'SHLVL': '1', 05:17:30 INFO - 'TERM': 'linux', 05:17:30 INFO - 'TMOUT': '86400', 05:17:30 INFO - 'USER': 'cltbld', 05:17:30 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:30 INFO - '_': '/tools/buildbot/bin/python'} 05:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:31 INFO - Running setup.py (path:/tmp/pip-N7Bck4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:31 INFO - Running setup.py (path:/tmp/pip-VhVR4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:31 INFO - Running setup.py (path:/tmp/pip-QOTzLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:31 INFO - Running setup.py (path:/tmp/pip-yPTLgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:31 INFO - Running setup.py (path:/tmp/pip-x6s7yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:31 INFO - Running setup.py (path:/tmp/pip-Fn00m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:31 INFO - Running setup.py (path:/tmp/pip-VB5AQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:31 INFO - Running setup.py (path:/tmp/pip-9wZZT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:31 INFO - Running setup.py (path:/tmp/pip-CzQgyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:31 INFO - Running setup.py (path:/tmp/pip-hWpOrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:31 INFO - Running setup.py (path:/tmp/pip-vg9KD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:31 INFO - Running setup.py (path:/tmp/pip-DcWF91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:31 INFO - Running setup.py (path:/tmp/pip-XUp9eJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:32 INFO - Running setup.py (path:/tmp/pip-JjhWWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:32 INFO - Running setup.py (path:/tmp/pip-dsSV15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:32 INFO - Running setup.py (path:/tmp/pip-Lg0QVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:32 INFO - Running setup.py (path:/tmp/pip-BvgH06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:17:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:34 INFO - Downloading blessings-1.6.tar.gz 05:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:34 INFO - Installing collected packages: blessings 05:17:34 INFO - Running setup.py install for blessings 05:17:34 INFO - Successfully installed blessings 05:17:34 INFO - Cleaning up... 05:17:34 INFO - Return code: 0 05:17:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:34 INFO - Reading from file tmpfile_stdout 05:17:34 INFO - Current package versions: 05:17:34 INFO - argparse == 1.2.1 05:17:34 INFO - blessings == 1.6 05:17:34 INFO - blobuploader == 1.2.4 05:17:34 INFO - docopt == 0.6.1 05:17:34 INFO - functools32 == 3.2.3-2 05:17:34 INFO - jsonschema == 2.5.1 05:17:34 INFO - manifestparser == 1.1 05:17:34 INFO - mozInstall == 1.12 05:17:34 INFO - mozcrash == 0.17 05:17:34 INFO - mozdebug == 0.1 05:17:34 INFO - mozdevice == 0.48 05:17:34 INFO - mozfile == 1.2 05:17:34 INFO - mozhttpd == 0.7 05:17:34 INFO - mozinfo == 0.9 05:17:34 INFO - mozleak == 0.1 05:17:34 INFO - mozlog == 3.2 05:17:34 INFO - moznetwork == 0.27 05:17:34 INFO - mozprocess == 0.23 05:17:34 INFO - mozprofile == 0.28 05:17:34 INFO - mozrunner == 6.12 05:17:34 INFO - mozscreenshot == 0.1 05:17:34 INFO - mozsystemmonitor == 0.3 05:17:34 INFO - moztest == 0.7 05:17:34 INFO - mozversion == 1.4 05:17:34 INFO - psutil == 3.1.1 05:17:34 INFO - requests == 1.2.3 05:17:34 INFO - wsgiref == 0.1.2 05:17:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:34 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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:17:34 INFO - Using env: {'DISPLAY': ':0', 05:17:34 INFO - 'HOME': '/home/cltbld', 05:17:34 INFO - 'LANG': 'en_US.UTF-8', 05:17:34 INFO - 'LANGUAGE': 'en_US:en', 05:17:34 INFO - 'LOGNAME': 'cltbld', 05:17:34 INFO - 'MAIL': '/var/mail/cltbld', 05:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:34 INFO - 'MOZ_NO_REMOTE': '1', 05:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:34 INFO - 'NO_EM_RESTART': '1', 05:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:34 INFO - 'PWD': '/builds/slave/test', 05:17:34 INFO - 'SHELL': '/bin/bash', 05:17:34 INFO - 'SHLVL': '1', 05:17:34 INFO - 'TERM': 'linux', 05:17:34 INFO - 'TMOUT': '86400', 05:17:34 INFO - 'USER': 'cltbld', 05:17:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:34 INFO - '_': '/tools/buildbot/bin/python'} 05:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:17:34 INFO - Cleaning up... 05:17:34 INFO - Return code: 0 05:17:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:17:34 INFO - Using env: {'DISPLAY': ':0', 05:17:34 INFO - 'HOME': '/home/cltbld', 05:17:34 INFO - 'LANG': 'en_US.UTF-8', 05:17:34 INFO - 'LANGUAGE': 'en_US:en', 05:17:34 INFO - 'LOGNAME': 'cltbld', 05:17:34 INFO - 'MAIL': '/var/mail/cltbld', 05:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:34 INFO - 'MOZ_NO_REMOTE': '1', 05:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:34 INFO - 'NO_EM_RESTART': '1', 05:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:34 INFO - 'PWD': '/builds/slave/test', 05:17:34 INFO - 'SHELL': '/bin/bash', 05:17:34 INFO - 'SHLVL': '1', 05:17:34 INFO - 'TERM': 'linux', 05:17:34 INFO - 'TMOUT': '86400', 05:17:34 INFO - 'USER': 'cltbld', 05:17:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:34 INFO - '_': '/tools/buildbot/bin/python'} 05:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:17:35 INFO - Cleaning up... 05:17:35 INFO - Return code: 0 05:17:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:35 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 0x7fbb5c8e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2171350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, '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': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:17:35 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 05:17:35 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 05:17:35 INFO - Using env: {'DISPLAY': ':0', 05:17:35 INFO - 'HOME': '/home/cltbld', 05:17:35 INFO - 'LANG': 'en_US.UTF-8', 05:17:35 INFO - 'LANGUAGE': 'en_US:en', 05:17:35 INFO - 'LOGNAME': 'cltbld', 05:17:35 INFO - 'MAIL': '/var/mail/cltbld', 05:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:35 INFO - 'MOZ_NO_REMOTE': '1', 05:17:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:35 INFO - 'NO_EM_RESTART': '1', 05:17:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:35 INFO - 'PWD': '/builds/slave/test', 05:17:35 INFO - 'SHELL': '/bin/bash', 05:17:35 INFO - 'SHLVL': '1', 05:17:35 INFO - 'TERM': 'linux', 05:17:35 INFO - 'TMOUT': '86400', 05:17:35 INFO - 'USER': 'cltbld', 05:17:35 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:35 INFO - '_': '/tools/buildbot/bin/python'} 05:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:35 INFO - Downloading/unpacking flake8 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:17:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:17:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:17:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:37 INFO - no previously-included directories found matching 'docs/_build' 05:17:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:17:38 INFO - Running setup.py install for pep8 05:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:38 INFO - no previously-included directories found matching 'docs/_build' 05:17:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:17:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:17:38 INFO - Cleaning up... 05:17:38 INFO - Return code: 0 05:17:38 INFO - Running post-action listener: _resource_record_post_action 05:17:38 INFO - Running post-action listener: _start_resource_monitoring 05:17:38 INFO - Starting resource monitoring. 05:17:38 INFO - ##### 05:17:38 INFO - ##### Finished create-virtualenv step (success) 05:17:38 INFO - ##### 05:17:38 INFO - ##### 05:17:38 INFO - ##### Running install step. 05:17:38 INFO - ##### 05:17:38 INFO - Running pre-action listener: _resource_record_pre_action 05:17:38 INFO - Running main action method: install 05:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:38 INFO - Reading from file tmpfile_stdout 05:17:38 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:38 INFO - Reading from file tmpfile_stdout 05:17:38 INFO - Output received: 05:17:38 INFO - Usage: mozinstall [options] installer 05:17:38 INFO - Options: 05:17:38 INFO - -h, --help show this help message and exit 05:17:38 INFO - -d DEST, --destination=DEST 05:17:38 INFO - Directory to install application into. [default: 05:17:38 INFO - "/builds/slave/test"] 05:17:38 INFO - --app=APP Application being installed. [default: firefox] 05:17:38 INFO - mkdir: /builds/slave/test/build/application 05:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:17:51 INFO - Reading from file tmpfile_stdout 05:17:51 INFO - Output received: 05:17:51 INFO - /builds/slave/test/build/application/firefox/firefox 05:17:51 INFO - Running post-action listener: _resource_record_post_action 05:17:51 INFO - ##### 05:17:51 INFO - ##### Finished install step (success) 05:17:51 INFO - ##### 05:17:51 INFO - ##### 05:17:51 INFO - ##### Running run-tests step. 05:17:51 INFO - ##### 05:17:51 INFO - Running pre-action listener: _resource_record_pre_action 05:17:51 INFO - Running main action method: run_tests 05:17:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:51 INFO - Python 2.7.3 05:17:51 INFO - Return code: 0 05:17:51 INFO - grabbing minidump binary from tooltool 05:17: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')]} 05:17:51 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 0x22001c0>, '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 0x22010c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2201550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:17:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:17:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:17:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:51 INFO - Return code: 0 05:17:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:17:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:17:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:17:52 INFO - Return code: 0 05:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:17:52 INFO - Using env: {'DISPLAY': ':0', 05:17:52 INFO - 'HOME': '/home/cltbld', 05:17:52 INFO - 'LANG': 'en_US.UTF-8', 05:17:52 INFO - 'LANGUAGE': 'en_US:en', 05:17:52 INFO - 'LOGNAME': 'cltbld', 05:17:52 INFO - 'MAIL': '/var/mail/cltbld', 05:17:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:52 INFO - 'MOZ_NO_REMOTE': '1', 05:17:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:52 INFO - 'NO_EM_RESTART': '1', 05:17:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:52 INFO - 'PWD': '/builds/slave/test', 05:17:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:52 INFO - 'SHELL': '/bin/bash', 05:17:52 INFO - 'SHLVL': '1', 05:17:52 INFO - 'TERM': 'linux', 05:17:52 INFO - 'TMOUT': '86400', 05:17:52 INFO - 'USER': 'cltbld', 05:17:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354', 05:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:52 INFO - '_': '/tools/buildbot/bin/python'} 05:17:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:17:52 INFO - mozversion application_buildid: 20160803022841 05:17:52 INFO - mozversion application_changeset: 781e498746664fbff97f752ba37065f98eded8c2 05:17:52 INFO - mozversion application_display_name: Firefox 05:17:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:52 INFO - mozversion application_name: Firefox 05:17:52 INFO - mozversion application_remotingname: firefox 05:17:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:17:52 INFO - mozversion application_vendor: Mozilla 05:17:52 INFO - mozversion application_version: 49.0 05:17:52 INFO - mozversion platform_buildid: 20160803022841 05:17:52 INFO - mozversion platform_changeset: 781e498746664fbff97f752ba37065f98eded8c2 05:17:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:17:52 INFO - mozversion platform_version: 49.0 05:17:52 INFO - using testdate: 1470226672 05:17:52 INFO - actual date: 1470226672 05:17:52 INFO - starting webserver on 'localhost:55366' 05:17:52 INFO - SUITE-START | Running 2 tests 05:17:52 INFO - TEST-START | dromaeo_css 05:17:52 INFO - operating with platform_type : linux_ 05:17:52 INFO - Initialising browser for dromaeo_css test... 05:17:52 INFO - TEST-INFO | started process 11862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV8FhmU/profile http://localhost:55366/getInfo.html) 05:17:56 INFO - PROCESS | 11862 | __metrics Screen width/height:1600/1200 05:17:56 INFO - PROCESS | 11862 | colorDepth:24 05:17:56 INFO - PROCESS | 11862 | Browser inner width/height: 1024/697 05:17:56 INFO - PROCESS | 11862 | __metrics 05:17:57 INFO - PROCESS | 11862 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:17:57 ERROR - PROCESS | 11862 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:17:58 INFO - TEST-INFO | 11862: exit 0 05:17:58 INFO - Browser initialized. 05:17:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:17:58 INFO - TEST-INFO | started process 11954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV8FhmU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:10 INFO - PROCESS | 11954 | RSS: Main: 141115392 05:18:10 INFO - PROCESS | 11954 | 05:19:01 INFO - PROCESS | 11954 | 1470226741031 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:21:09 INFO - PROCESS | 11954 | Cycle 1(1): loaded http://localhost:55366/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55366/tests/dromaeo/cssquery-ext.html) 05:21:10 INFO - PROCESS | 11954 | RSS: Main: 244838400 05:21:10 INFO - PROCESS | 11954 | 05:24:12 INFO - PROCESS | 11954 | Cycle 1(1): loaded http://localhost:55366/tests/dromaeo/cssquery-ext.html (next: http://localhost:55366/tests/dromaeo/cssquery-jquery.html) 05:24:13 INFO - PROCESS | 11954 | RSS: Main: 234176512 05:24:13 INFO - PROCESS | 11954 | 05:27:15 INFO - PROCESS | 11954 | Cycle 1(1): loaded http://localhost:55366/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55366/tests/dromaeo/cssquery-mootools.html) 05:27:15 INFO - PROCESS | 11954 | RSS: Main: 224301056 05:27:15 INFO - PROCESS | 11954 | 05:30:17 INFO - PROCESS | 11954 | Cycle 1(1): loaded http://localhost:55366/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55366/tests/dromaeo/cssquery-prototype.html) 05:30:18 INFO - PROCESS | 11954 | RSS: Main: 224686080 05:30:18 INFO - PROCESS | 11954 | 05:33:20 INFO - PROCESS | 11954 | Cycle 1(1): loaded http://localhost:55366/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55366/tests/dromaeo/cssquery-yui.html) 05:33:21 INFO - PROCESS | 11954 | RSS: Main: 212029440 05:33:21 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | Cycle 1(1): loaded http://localhost:55366/tests/dromaeo/cssquery-yui.html (next: http://localhost:55366/tests/dromaeo/cssquery-dojo.html) 05:36:23 INFO - PROCESS | 11954 | RSS: Main: 254750720 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | __start_tp_report 05:36:23 INFO - PROCESS | 11954 | _x_x_mozilla_page_load 05:36:23 INFO - PROCESS | 11954 | _x_x_mozilla_page_load_details 05:36:23 INFO - PROCESS | 11954 | |i|pagename|runs| 05:36:23 INFO - PROCESS | 11954 | |0;dojo.html;3377.223238655109;3391.186115332321;3395.083327501576;3412.488126780984;3429.6913277805006;5564.471375219353;5613.859653508664;5614.9438505614935;5634;5636.1264004079385;5511.641743286684;5516.089845175551;5547.750351234194;5569.387367389584;5572.4706152915505;4894.290327902452;4914.655974081807;4921.778519966592;4959.156943319645;5023.598112151038;4946.530079642428;4960.7767650455635;4967.850964471072;4969.329140566031;5002.1496345621335;5010.949890501103;5035;5095.643304968654;5105.132127538327;5129.820456284031;4987.925181122249;5037.143685573461;5086.364204474441;5086.97456512717;5099.4900509949075;4888.266759985995;4892.730899800509;4954.851354459372;4972.328735620681;4995.375578052744;5495.972520137374;5544.0575102232715;5549.6115271930985;5551.084071128259;5581.832545023656;5761.366249712528;5968.164456975984;5984.970075149597;6018.8495287617725;6021.217241758587;5936.7328470218945;5954.523638108941;5986.161937328778;6085.300190478108;6097.756089756404;5652.406497317805;5668.659880407147;5671.999999999959;5675.9432405675825;5677.545796336283;5924.881502369928;6136.110264011693;6174.44430001302;6192.752289908443;6195.070739389128;5804.622699524517;5875.089361149029;5886.941130588725;5946.851328716817;5952.85117872048;5990.5507086968655;6025.6685882276015;6027.517798576104;6032.517398608188;6039.305479869871;5757.85605359861;5964.135200395918;5977.043673012297;5977.970110149421;5979.790707325223;5843.649381037151;5868.823935281948;5905.1142328650185;5937.406259374028;5946.762129514857;4481.215787237221;4482.686211965131;4483.125790470827;4490.281554951192;4510.345999830072;3943.152222272225;4017.5379831319187;4017.819198136091;4033.8991525212105;4058.6144316289906;5562.137868630369;5621.353544342372;5627.212190293362;5662.348829884533;5669.858253543694;6032.758689652364;6045.78839740607;6049.21360223177;6053.152558641794;6093.542984276197;5426.050441172779;5583.748731307101;5636.859078522988;5679.744411501492;5682.034054210794;4985.501449854985;5024.472430394829;5030.346055012825;5037.420696619935;5065;6206.193194884636;6229.470495007975;6248.125262463259;6248.187735594343;6257.5932564383165;5804.071348584206;5851.239338885917;5908.881822363614;6047.606905551125;6058.394160584083;6196.287426945959;6203.844903877439;6219.533534984804;6219.906701399392;6230.470410015199;5922.881542369042;5932.79235226765;5937.139114828239;5942.524598032233;5970.044792833126;5831.854203644943;5898.026825573732;5938.287405511366;5974.880502390015;6024.8795024100145;5779.7110144493445;5806.071028635398;5823.301203855564;5842.766289348378;5846.210761547307;5799.101139323411;5834.15404766298;5845.152452894222;5848.853778655568;5853.590248682552;5593.300837395325;5628.802991895264;5649.180868774087;5678.659280443187;5679.176519404746;5774.0761478163295;5851.063829787214;5864.648121112747;5876.882462350814;5879.882402352015;5592.3009623797025;5725.599208055396;5727.341355744143;5729;5754.712264386848 05:36:23 INFO - PROCESS | 11954 | |1;ext.html;598.7694737526051;638.2723694987714;657.0866495571154;665.6871270502863;670.7987603718883;33156;47269.527304726944;48149.27776083353;48189.27716084261;48234.51765482344;49724.50275497244;50079.24881126777;50123.49876501234;50217.24674129882;50308.74845625776;8320.251177394033;9174.40366376185;9331.113544213324;9332.160105590496;10848.18638602108;10630.096441802456;11003.17476189289;11048.06091482225;11075.612353567627;11085.224034317602;11131.220814542985;11168.218224724273;11173.83239251412;11175.944120279428;11179.66461006171;10976.890231097666;11013.339199648046;11046.944765276203;11052.557897684082;11056.447177641126;10614.522346494427;10635.734106647318;10652;10670;10676.786464270748;45417.2749635023;45847.77076114598;45886.77056614696;45892.311615325794;45911;3561.8753343632848;3576.2668652926086;3596.496490491333;3603.1172362771026;3786.1291902862495;34899.4765078524;35230.82384588086;35502.822485887664;35640;35911.82044089763;4251.086016506466;4254.298040823261;4271.829126834923;4282.5503322151035;4284.228838809013;385980;386567;387058;387416.06291968643;390206;38430.309878308806;64129.67935160294;64723.35276647267;64828.675856620415;64828.67585662136;42248.15503689909;42413.36379954303;42438.151236975085;42468.57531424708;42500.149996999884;17431;17532.737008944878;17580.12099395025;17584.208710607796;17612.383566575365;13126.474941002443;14726.484573039892;15037.097774133586;15040.4207558207;15313.693726125415;9437.952810235905;9442.716718498456;9476.526173691287;9487.14615684578;9546.713598592054;3600.927981440409;3611.7832930024283;3613.873514427035;3637.9818100909324;3644.107193737498;1125.971850703739;1152.475623591264;1173.5188572685158;1189.2032338333338;1245.5640525816073;33943.4908476373;34557.65442345595;34995.65004349975;35133;35237.47143792845;1116.3023110555903;1138.5502726423017;1143.988560114405;1247.1643998521013;1258.8363512743285;18140.546486337684;18393.448196554124;18449.81550184481;18475.445736627924;18636.72044919327;577803;580122;580796;581148;582217;66121.66939165369;66475.66762166159;66478;66520;66565.00152497717;1481.7703255995339;1499.1080307217405;1511.8185817701947;1513.1072667126448;1526.8778497720157;33778;33995;34957.30085398329;35064;35115.47326790049;54415.455845441036;55047;55106.44893551013;55133.724331378085;55162;425037;434009;434646;435574;435815;27321.180364589094;31832.52251216187;32035.839820800746;32292;32691.34617307593;50722.2391664118;50976.74511627418;51393.48606513887;51578.48421515737;51819;42611.78694106633;43379.566204337556;43957;44412.333814992155;44855;42478.78760606177;42752.786236069864;43262.134757305306;43843.780781097164;44069.559304406546;42763.57236427596;43048.354274685276;43523.56476435195;44298;44583.55416445794;38022.23955520929;38892;39034.804825976826;39278.21443571056;39341.803290983364;29099.272518187216;29256;29388;29523.409531809673;29564.113076607737 05:36:23 INFO - PROCESS | 11954 | |2;jquery.html;388.20030736682435;390.72258696325605;413.7931034482759;414.46327006526536;430.7157276197708;14480.493182738606;18759.718604220932;19912.90043549784;20342.08460619272;20380.59238815223;19488.41534753956;19595.118219680113;19805.504862378446;19809.801901980976;19863.30478433255;5604.523615492678;5843.707814609274;5849.268841394825;5857.209276747649;5870.383609720981;5649.661020338773;5766.394528574502;5809.651420914759;5812.767489300444;5813.157092221625;4265.274903266437;5997.940020599804;6302.62184268943;6303.149074874879;6392.3927226913365;6320.494360451153;6347.492200623939;6370.044493325991;6373.490120790349;6470.09418681385;5486.890262194734;5568.554515638739;5581.386047534768;5593.74828132735;5604.579656525777;23372.298831035096;23472.765272347227;23950;24158.275251742474;24394.51210975788;2416.28719527738;2504.3989442533843;2552.770250677448;2802.7477527022666;2963.096255642028;14553.563393098224;14986.400543978229;15139.848601514064;15396.307166177548;15537.922310388489;1999.5001249687577;2066.741657292838;2106.7050612914204;2125.9362219133295;2151.741790985076;59846;65256;72939;80335.5983220086;86690.99999999937;10648.893511064945;10689.358638481715;11104.500297486633;11119.110471162288;11320.716982075513;15289.694206115815;15649.687006259812;15655.138967356677;15655.295511702001;15669;8754.51850148248;8849.823003539894;8855.91144088551;8876.556172191365;8881.955590222007;8568.400211985227;8612.8708069379;8665.436746611576;8720.389572729975;8758.036615972238;6842.384185423336;6935.445164386837;6944.583325000516;6984.42520149303;7084.893726594105;1865.0581456364448;1958.1482055305987;1967.1836187982112;1968.8031196880484;1968.9704654430195;839.8656215005569;946.4510583861344;1014.2899970021015;1145.919785614999;1146.340854008948;19861.30485433003;20002.39992800218;20016;20171.193152273743;20239.291624793063;1092.5629748100823;1100.2298391126244;1113.1317572293624;1153.8038533449335;1155.8092914668985;10655.627053053106;11329;11455.083593312514;11528.942355288169;11774.823377649342;533839;549383;558693;564924;566793;96671;98254;99870;100233;101796;1104.9116070714324;1110.9611163609236;1122.9775404491863;1133.5919069135105;1136.6760473265244;17873.73189402186;17931.73102403491;18005.2797888083;18182.72725909139;18201.908990454962;40560;41219.38170927498;42139.1572168561;42448.57551424446;42919.14161716686;61231.693841530505;61476.69261653842;61979.690101549204;62385;63306;7747.186545412876;8105.878411823709;8281.917180828355;8518.787030324043;8691.348148888732;12982.675433113869;13780.793288100887;13838.723225535232;13846.238456884968;13998.580042598738;12075.637730868088;12084.516619335129;12196.57311994076;12210.511579537075;12592.74814503723;11466.541338346373;11685.707857303636;11895.464704088165;12208.511659533518;12569.371531423576;11331.150163737588;12111.212771169845;12179.939100304442;12198.817017744563;12811.743765124458;8859.468431894107;8912.821743565222;8939.73180804577;9164.266858651425;9181.357304988587;11225.101991840469;11635.41822908868;11746.882531174579;12028.819567706667;12515 05:36:23 INFO - PROCESS | 11954 | |3;mootools.html;1369.7055133146384;1576.6610178811554;1600.503843808419;1788.5886246163382;1928.8456923446117;8701.869471957925;9080.04659510751;9080.591373388197;9391.060893910606;9403.670871519498;9079.91041075072;9271.026542213054;9277.489738064403;9278.396904201221;9284.303677224218;2389.7132344118686;2411.3007227903972;2411.9276421707377;2418.6855708757835;2426.417659761662;2382.642603609455;2406.0496104038893;2406.0736616402723;2407.4823912858733;2418.2745176446997;2383.8200090955;2403.735589085199;2404.1345115758313;2428.854268743872;2437.7196622388474;2333.673285739998;2345.4488195274184;2368.763123687626;2373.6439534070028;2375.0262392419113;2276.3398614401804;2324.4886125052462;2324.7442781294044;2326.255598208574;2332.8367014309006;8062.798430039237;8264.83470330596;9185.265178785681;9350.766230844216;9353.158215760614;5182.844514664566;5323.600729945269;5334.119870221409;5435.918461223046;5438.864028399282;3504.7196224301993;3522.3131489359585;3563.1626567756816;3567.4113771227717;3618.5838628557526;1134.1947217475602;1147.9311241325547;1151.851796399717;1151.995527781654;1152.0070278418384;13027.674308142277;13102;13132.737345253137;13143.605691829165;13162;4722.6221902247735;4749.715017098985;4793.736344501016;4881.316615673809;4910.48439913811;11043.779124417466;11065.004149626573;11068.723281918017;11068.889311106946;11071.225014248925;5454.7818087276855;5457.645253058538;5458.781648734006;5460.781568737286;5463.235147079412;5018.347614810052;5073.34046573943;5145.562627176657;5165.354330708661;5169.948300517022;4013.8996525087105;4062.5937406259136;4118.073433477443;4136.565660605654;4163.937540936889;1253.529926277646;1253.6991122130623;1259.7354555543254;1280.5774094548776;1284.5118854835102;1044.6918159143072;1057.3021805608416;1070.309650275568;1079.3577821196532;1080.9729756755987;6216.595921265103;6393.424591786761;6399.9120149573755;6429.164208652939;6485.805425837232;1014.2950649298666;1027.6300531808542;1027.825269704137;1075.7310672331917;1116.24749143845;4367.475902891673;4425.446819147606;4470.441194850644;4500.369948207253;4522.095580883836;11496.655100347003;11886.049116070866;11934;11940.641780746591;11952.641420757218;11469.25449845774;11813.586524471602;11836.822447663291;11838.348890811088;11889.643310700692;8576.656933722581;8616.267617252479;8822.823543529223;8872.8669069964;8962.731118066598;6305.527085468517;6384.7446102157255;6394.1687580614225;6504.1219435375615;6584.901226481702;10579.62971296001;10749.89250107489;10836.13310935139;10845.620403285848;11070.114390848552;10674.199435042558;11013.118950484102;11016;11166.944165279121;11178.0498657615;8331.333493320399;8331.916680833114;8433.620487077973;8458.619362128842;8471.449355791854;9027.909720902708;9038;9104.13510716481;9217.539123043955;9229.630814767603;8121.106678265504;8129.146439623754;8155.877661835196;8391.664333426594;8431.40980131385;7739.187385324685;8012.198780121941;8019.478733882279;8087.47431416956;8208.09710931785;7278.163011253773;7321.048263725682;7360.153582337885;7365.300296471826;7366.521176123535;5476.808311709071;5507.366652834975;5563.693996830213;5609.270794796814;5673.659580425187;4924.162892308244;5420.403755586961;5492.752826122914;5591.832245032656;5692.00389931752 05:36:23 INFO - PROCESS | 11954 | |4;prototype.html;342.83201231396606;344.5314372453463;351.7432274439658;356.2820915854552;359.28322995623734;5471.835844924653;5572.4148964358665;5582.469665381787;5606.495415412623;5616.831495055149;5463.262459567957;5509;5557.694326812024;5574.637648552841;5580.302462192226;3866.4340941918567;4099.622219112922;4108.732932359395;4236.301010333292;4283.7429754214845;4079.1637714268654;4211.136716973013;4269.502341130443;4269.914601707964;4270.551592082833;4167.249895018896;4176.770277634729;4187.853425130121;4215.599518045796;4227.513835908879;4034.09232922593;4134.9999999999845;4217.650351887397;4224.725392849455;4239.554846741079;3746.83139258734;3770.2459508098214;3782.8108594570435;3785;3788.867389641377;5246.982984783765;5264.15773476242;5297.894042119175;5310.468953104697;5327.973360133214;4814.181589129821;4821.662483626147;4856.247281671346;4864.659473836833;4893.241547560134;5612.635178713412;5662.009148399014;5666.320041594994;5673.6879471629045;5689.236336735626;4175.457190565237;4183.895402614959;4199.202151591188;4200.873973780792;4212.431321771583;5593.468620481048;5682.971585142131;5774.7295594969455;5850.162671104511;5867.559933005042;4246.299360605465;4253.298205796009;4271.380649805823;4274.380214868826;4278.978605107017;4302.666173486166;4342.5874541918465;4348.999460248321;4363.323684828857;4369.169857727022;4614.307853821953;4812;4907.975460122748;4923.236898280772;4949.727764972889;5693.430656934273;5715.228444159985;5731.77072917087;5753.33836608787;5816.185733997244;1453.7570377327445;1471.720373129102;1473.955781326562;1475.5573328001556;1478.7652310321002;914.7833381602453;931.2131249094505;943.5942544705842;946.0350442548562;956.6747305916022;4931.5561599455505;4955.628327875424;4971;4982.60139188864;4993.300937868701;5253.894922101536;5287.206918962186;5318.574514038945;5327.348521958205;5348.465153484621;5032.245163225472;5040.344755181876;5058.797648094035;5271.604629652715;5683;3118.6257649082254;3149.5590617313596;3173.8889138880027;3186.4423725847882;3217.4691175956173;6078.45293923549;6131.608672617683;6136.514711541833;6171.907421388682;6172.938270617326;5781.6241944274325;6006;6021.862238905213;6025.548083893726;6051.697415129226;6109.327973922865;6150.7232174552255;6171.783987560414;6176;6190.969045154746;5637.913971830285;5941.316748573797;5942;5980.880382392241;5993.460588547052;5823.388544202967;5860.560457965755;5863.208466857091;5880.50015748649;5894.911576326444;5855.560832937632;5973.104034394875;6118.235220597426;6133.975787990042;6140.385961403824;4135.407867970793;4148.605882441163;4162.2716024696165;4183.958160418357;4192.098698779777;5800.738966746422;5910.852228694317;5982.760689572368;6006.969965150146;6017.969910150421;5708.686022268649;5718.1708652246025;5741.8564535886935;5754.597178197653;5773.624714393551;5728.226689396878;5736.4550367715;5741.5119714824705;5783.132530120515;5783.16144159103;5476.2607048048;5685.232493613475;5701.942980570141;5709.571782116275;5711.114777209539;5783.248177736868;5808.5353171747;5809.738561764646;5826.475617194473;5838.2702162229725;5423.83387571674;5582.469665381782;5708.942910571007;5726.455986681259;5729.281215635376 05:36:23 INFO - PROCESS | 11954 | |5;yui.html;767.0641816983285;781.5232708048089;815.6085079161999;871.3726117195619;911.535117090284;16013.83986160138;16218.513444596652;16300;16516.174191290447;16637;14923.701525969474;14943.178125203136;15067.17130557819;15104.169270690109;15114.6977060459;731.6707481633259;881.0792721605937;902.0528445132617;903.9593218305159;916.9633214671405;895.6104094718818;899.4962820820328;902.7066203483861;905.6784841381314;905.8777065096193;944.3719926249037;949.9857502137439;954.9379290346105;956.2827879090682;956.9377990430635;777.8055486128468;921.5438358012793;952.7618095476131;953.7472569768983;954.0459540459541;704.4082970304917;704.8766465868453;705.048184950318;705.1045172630782;705.2735682247294;14878;14880.62798430037;14970.101793892401;15019.699606007818;15022.924885375613;14285.57143285703;14373.281335933161;14702.485413010494;14730.55808325752;14894.329755160936;12574.559890403792;12781.61655150347;12839.935800321033;12950.158239714483;13013.86986130136;4639.373684552576;4687.648426368036;4714.504976977402;4745.738984355859;4747.430308362984;74855;75377;75778.86331705029;76347.23652763457;76820;11123.388213648326;11157.66527004191;11236.550537978554;11299.717507062389;11561.248518846249;19828.70256946147;19862;19902.50243743918;20059.39821805348;20091.698624520643;8364.079951205362;8439.535825529654;8521.829563408697;8544.786380340542;8560.630299152106;5140.485951404829;5153.665011774223;5154.098032844237;5160.612954028463;5184.688918664892;3503.649635036476;3614.584322802859;3617.638236176414;3621.7464777465852;3622.347977364047;634.6255709131634;651.837040739815;659.9043138744855;674.089978528982;680.9693563789542;615.4030590327375;639.341478277375;681.8159097043771;701.6176183979743;707.4976766495743;9410.200132988635;9459.19596834263;9470.621175152917;9501.382410143318;9539.713808585753;675.2572170612287;734.1447213995635;745.671904362079;749.3480671815446;777.9183185765527;4648.674592778473;4686;4712.717236965793;4830.275458681226;4834.250691142878;68923.3107668917;69086.65456672786;69402.65298673576;86397.56801215954;113663;76065.23934760582;97022;97272.51363743136;98648.50675746576;99172;89324.5533772327;89659.55170224106;89725;89768;90352.5482372584;9140.6800761973;9178.954105229432;9225.108763206961;9288.11762882525;9332.39339442934;52325.21512177397;53158.73420632872;53398;53423.19865202103;54812.45187548233;69142.65428672824;74174;74908;76065.61967190128;78556;11745.941270293593;11863;11969.64091077269;12468.251904885736;12820.487180512675;12604.432800524182;13611.931940340632;14480.85519144795;15071;15200.543983680507;14490.85509144895;14732.189729564549;15455.381784728484;15664.686706266039;15939.681206376039;14724;14759.262036897897;15171.3519419885;15243.466478673194;15442.2278886053;11239.66281011571;11729.530818767154;13950.232737199145;14133.575992720234;14562.781558276423;11394.601188958346;11459.369734664673;12063;12334.81497777552;12378.566750163702;12197.146199765932;12205.20666156697;12234.816477753018;12340.136190466581;12367.876321236672 05:36:23 INFO - PROCESS | 11954 | __end_tp_report 05:36:23 INFO - PROCESS | 11954 | __start_cc_report 05:36:23 INFO - PROCESS | 11954 | _x_x_mozilla_cycle_collect,825 05:36:23 INFO - PROCESS | 11954 | __end_cc_report 05:36:23 INFO - PROCESS | 11954 | __startTimestamp1470227783960__endTimestamp 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | ------- Summary: start ------- 05:36:23 INFO - PROCESS | 11954 | Number of tests: 6 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | [#0] dojo.html Cycles:165 Average:5531.19 Median:5727.34 stddev:634.92 (11.1%) stddev-sans-first:613.97 05:36:23 INFO - PROCESS | 11954 | Values: 3377.2 3391.2 3395.1 3412.5 3429.7 5564.5 5613.9 5614.9 5634.0 5636.1 5511.6 5516.1 5547.8 5569.4 5572.5 4894.3 4914.7 4921.8 4959.2 5023.6 4946.5 4960.8 4967.9 4969.3 5002.1 5010.9 5035.0 5095.6 5105.1 5129.8 4987.9 5037.1 5086.4 5087.0 5099.5 4888.3 4892.7 4954.9 4972.3 4995.4 5496.0 5544.1 5549.6 5551.1 5581.8 5761.4 5968.2 5985.0 6018.8 6021.2 5936.7 5954.5 5986.2 6085.3 6097.8 5652.4 5668.7 5672.0 5675.9 5677.5 5924.9 6136.1 6174.4 6192.8 6195.1 5804.6 5875.1 5886.9 5946.9 5952.9 5990.6 6025.7 6027.5 6032.5 6039.3 5757.9 5964.1 5977.0 5978.0 5979.8 5843.6 5868.8 5905.1 5937.4 5946.8 4481.2 4482.7 4483.1 4490.3 4510.3 3943.2 4017.5 4017.8 4033.9 4058.6 5562.1 5621.4 5627.2 5662.3 5669.9 6032.8 6045.8 6049.2 6053.2 6093.5 5426.1 5583.7 5636.9 5679.7 5682.0 4985.5 5024.5 5030.3 5037.4 5065.0 6206.2 6229.5 6248.1 6248.2 6257.6 5804.1 5851.2 5908.9 6047.6 6058.4 6196.3 6203.8 6219.5 6219.9 6230.5 5922.9 5932.8 5937.1 5942.5 5970.0 5831.9 5898.0 5938.3 5974.9 6024.9 5779.7 5806.1 5823.3 5842.8 5846.2 5799.1 5834.2 5845.2 5848.9 5853.6 5593.3 5628.8 5649.2 5678.7 5679.2 5774.1 5851.1 5864.6 5876.9 5879.9 5592.3 5725.6 5727.3 5729.0 5754.7 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | [#1] ext.html Cycles:180 Average:63352.08 Median:386812.50 stddev:125793.15 (32.5%) stddev-sans-first:126057.79 05:36:23 INFO - PROCESS | 11954 | Values: 598.8 638.3 657.1 665.7 670.8 33156.0 47269.5 48149.3 48189.3 48234.5 49724.5 50079.2 50123.5 50217.2 50308.7 8320.3 9174.4 9331.1 9332.2 10848.2 10630.1 11003.2 11048.1 11075.6 11085.2 11131.2 11168.2 11173.8 11175.9 11179.7 10976.9 11013.3 11046.9 11052.6 11056.4 10614.5 10635.7 10652.0 10670.0 10676.8 45417.3 45847.8 45886.8 45892.3 45911.0 3561.9 3576.3 3596.5 3603.1 3786.1 34899.5 35230.8 35502.8 35640.0 35911.8 4251.1 4254.3 4271.8 4282.6 4284.2 385980.0 386567.0 387058.0 387416.1 390206.0 38430.3 64129.7 64723.4 64828.7 64828.7 42248.2 42413.4 42438.2 42468.6 42500.1 17431.0 17532.7 17580.1 17584.2 17612.4 13126.5 14726.5 15037.1 15040.4 15313.7 9438.0 9442.7 9476.5 9487.1 9546.7 3600.9 3611.8 3613.9 3638.0 3644.1 1126.0 1152.5 1173.5 1189.2 1245.6 33943.5 34557.7 34995.7 35133.0 35237.5 1116.3 1138.6 1144.0 1247.2 1258.8 18140.5 18393.4 18449.8 18475.4 18636.7 577803.0 580122.0 580796.0 581148.0 582217.0 66121.7 66475.7 66478.0 66520.0 66565.0 1481.8 1499.1 1511.8 1513.1 1526.9 33778.0 33995.0 34957.3 35064.0 35115.5 54415.5 55047.0 55106.4 55133.7 55162.0 425037.0 434009.0 434646.0 435574.0 435815.0 27321.2 31832.5 32035.8 32292.0 32691.3 50722.2 50976.7 51393.5 51578.5 51819.0 42611.8 43379.6 43957.0 44412.3 44855.0 42478.8 42752.8 43262.1 43843.8 44069.6 42763.6 43048.4 43523.6 44298.0 44583.6 38022.2 38892.0 39034.8 39278.2 39341.8 29099.3 29256.0 29388.0 29523.4 29564.1 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | [#2] jquery.html Cycles:180 Average:31328.90 Median:23422.53 stddev:91162.44 (389.2%) stddev-sans-first:91388.57 05:36:23 INFO - PROCESS | 11954 | Values: 388.2 390.7 413.8 414.5 430.7 14480.5 18759.7 19912.9 20342.1 20380.6 19488.4 19595.1 19805.5 19809.8 19863.3 5604.5 5843.7 5849.3 5857.2 5870.4 5649.7 5766.4 5809.7 5812.8 5813.2 4265.3 5997.9 6302.6 6303.1 6392.4 6320.5 6347.5 6370.0 6373.5 6470.1 5486.9 5568.6 5581.4 5593.7 5604.6 23372.3 23472.8 23950.0 24158.3 24394.5 2416.3 2504.4 2552.8 2802.7 2963.1 14553.6 14986.4 15139.8 15396.3 15537.9 1999.5 2066.7 2106.7 2125.9 2151.7 59846.0 65256.0 72939.0 80335.6 86691.0 10648.9 10689.4 11104.5 11119.1 11320.7 15289.7 15649.7 15655.1 15655.3 15669.0 8754.5 8849.8 8855.9 8876.6 8882.0 8568.4 8612.9 8665.4 8720.4 8758.0 6842.4 6935.4 6944.6 6984.4 7084.9 1865.1 1958.1 1967.2 1968.8 1969.0 839.9 946.5 1014.3 1145.9 1146.3 19861.3 20002.4 20016.0 20171.2 20239.3 1092.6 1100.2 1113.1 1153.8 1155.8 10655.6 11329.0 11455.1 11528.9 11774.8 533839.0 549383.0 558693.0 564924.0 566793.0 96671.0 98254.0 99870.0 100233.0 101796.0 1104.9 1111.0 1123.0 1133.6 1136.7 17873.7 17931.7 18005.3 18182.7 18201.9 40560.0 41219.4 42139.2 42448.6 42919.1 61231.7 61476.7 61979.7 62385.0 63306.0 7747.2 8105.9 8281.9 8518.8 8691.3 12982.7 13780.8 13838.7 13846.2 13998.6 12075.6 12084.5 12196.6 12210.5 12592.7 11466.5 11685.7 11895.5 12208.5 12569.4 11331.2 12111.2 12179.9 12198.8 12811.7 8859.5 8912.8 8939.7 9164.3 9181.4 11225.1 11635.4 11746.9 12028.8 12515.0 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | [#3] mootools.html Cycles:180 Average:6151.75 Median:4396.46 stddev:3542.49 (80.6%) stddev-sans-first:3534.20 05:36:23 INFO - PROCESS | 11954 | Values: 1369.7 1576.7 1600.5 1788.6 1928.8 8701.9 9080.0 9080.6 9391.1 9403.7 9079.9 9271.0 9277.5 9278.4 9284.3 2389.7 2411.3 2411.9 2418.7 2426.4 2382.6 2406.0 2406.1 2407.5 2418.3 2383.8 2403.7 2404.1 2428.9 2437.7 2333.7 2345.4 2368.8 2373.6 2375.0 2276.3 2324.5 2324.7 2326.3 2332.8 8062.8 8264.8 9185.3 9350.8 9353.2 5182.8 5323.6 5334.1 5435.9 5438.9 3504.7 3522.3 3563.2 3567.4 3618.6 1134.2 1147.9 1151.9 1152.0 1152.0 13027.7 13102.0 13132.7 13143.6 13162.0 4722.6 4749.7 4793.7 4881.3 4910.5 11043.8 11065.0 11068.7 11068.9 11071.2 5454.8 5457.6 5458.8 5460.8 5463.2 5018.3 5073.3 5145.6 5165.4 5169.9 4013.9 4062.6 4118.1 4136.6 4163.9 1253.5 1253.7 1259.7 1280.6 1284.5 1044.7 1057.3 1070.3 1079.4 1081.0 6216.6 6393.4 6399.9 6429.2 6485.8 1014.3 1027.6 1027.8 1075.7 1116.2 4367.5 4425.4 4470.4 4500.4 4522.1 11496.7 11886.0 11934.0 11940.6 11952.6 11469.3 11813.6 11836.8 11838.3 11889.6 8576.7 8616.3 8822.8 8872.9 8962.7 6305.5 6384.7 6394.2 6504.1 6584.9 10579.6 10749.9 10836.1 10845.6 11070.1 10674.2 11013.1 11016.0 11166.9 11178.0 8331.3 8331.9 8433.6 8458.6 8471.4 9027.9 9038.0 9104.1 9217.5 9229.6 8121.1 8129.1 8155.9 8391.7 8431.4 7739.2 8012.2 8019.5 8087.5 8208.1 7278.2 7321.0 7360.2 7365.3 7366.5 5476.8 5507.4 5563.7 5609.3 5673.7 4924.2 5420.4 5492.8 5591.8 5692.0 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | [#4] prototype.html Cycles:180 Average:4798.28 Median:5467.55 stddev:1412.09 (25.8%) stddev-sans-first:1375.88 05:36:23 INFO - PROCESS | 11954 | Values: 342.8 344.5 351.7 356.3 359.3 5471.8 5572.4 5582.5 5606.5 5616.8 5463.3 5509.0 5557.7 5574.6 5580.3 3866.4 4099.6 4108.7 4236.3 4283.7 4079.2 4211.1 4269.5 4269.9 4270.6 4167.2 4176.8 4187.9 4215.6 4227.5 4034.1 4135.0 4217.7 4224.7 4239.6 3746.8 3770.2 3782.8 3785.0 3788.9 5247.0 5264.2 5297.9 5310.5 5328.0 4814.2 4821.7 4856.2 4864.7 4893.2 5612.6 5662.0 5666.3 5673.7 5689.2 4175.5 4183.9 4199.2 4200.9 4212.4 5593.5 5683.0 5774.7 5850.2 5867.6 4246.3 4253.3 4271.4 4274.4 4279.0 4302.7 4342.6 4349.0 4363.3 4369.2 4614.3 4812.0 4908.0 4923.2 4949.7 5693.4 5715.2 5731.8 5753.3 5816.2 1453.8 1471.7 1474.0 1475.6 1478.8 914.8 931.2 943.6 946.0 956.7 4931.6 4955.6 4971.0 4982.6 4993.3 5253.9 5287.2 5318.6 5327.3 5348.5 5032.2 5040.3 5058.8 5271.6 5683.0 3118.6 3149.6 3173.9 3186.4 3217.5 6078.5 6131.6 6136.5 6171.9 6172.9 5781.6 6006.0 6021.9 6025.5 6051.7 6109.3 6150.7 6171.8 6176.0 6191.0 5637.9 5941.3 5942.0 5980.9 5993.5 5823.4 5860.6 5863.2 5880.5 5894.9 5855.6 5973.1 6118.2 6134.0 6140.4 4135.4 4148.6 4162.3 4184.0 4192.1 5800.7 5910.9 5982.8 6007.0 6018.0 5708.7 5718.2 5741.9 5754.6 5773.6 5728.2 5736.5 5741.5 5783.1 5783.2 5476.3 5685.2 5701.9 5709.6 5711.1 5783.2 5808.5 5809.7 5826.5 5838.3 5423.8 5582.5 5708.9 5726.5 5729.3 05:36:23 INFO - PROCESS | 11954 | 05:36:23 INFO - PROCESS | 11954 | [#5] yui.html Cycles:180 Average:20009.56 Median:28754.95 stddev:27383.89 (95.2%) stddev-sans-first:27422.59 05:36:23 INFO - PROCESS | 11954 | Values: 767.1 781.5 815.6 871.4 911.5 16013.8 16218.5 16300.0 16516.2 16637.0 14923.7 14943.2 15067.2 15104.2 15114.7 731.7 881.1 902.1 904.0 917.0 895.6 899.5 902.7 905.7 905.9 944.4 950.0 954.9 956.3 956.9 777.8 921.5 952.8 953.7 954.0 704.4 704.9 705.0 705.1 705.3 14878.0 14880.6 14970.1 15019.7 15022.9 14285.6 14373.3 14702.5 14730.6 14894.3 12574.6 12781.6 12839.9 12950.2 13013.9 4639.4 4687.6 4714.5 4745.7 4747.4 74855.0 75377.0 75778.9 76347.2 76820.0 11123.4 11157.7 11236.6 11299.7 11561.2 19828.7 19862.0 19902.5 20059.4 20091.7 8364.1 8439.5 8521.8 8544.8 8560.6 5140.5 5153.7 5154.1 5160.6 5184.7 3503.6 3614.6 3617.6 3621.7 3622.3 634.6 651.8 659.9 674.1 681.0 615.4 639.3 681.8 701.6 707.5 9410.2 9459.2 9470.6 9501.4 9539.7 675.3 734.1 745.7 749.3 777.9 4648.7 4686.0 4712.7 4830.3 4834.3 68923.3 69086.7 69402.7 86397.6 113663.0 76065.2 97022.0 97272.5 98648.5 99172.0 89324.6 89659.6 89725.0 89768.0 90352.5 9140.7 9179.0 9225.1 9288.1 9332.4 52325.2 53158.7 53398.0 53423.2 54812.5 69142.7 74174.0 74908.0 76065.6 78556.0 11745.9 11863.0 11969.6 12468.3 12820.5 12604.4 13611.9 14480.9 15071.0 15200.5 14490.9 14732.2 15455.4 15664.7 15939.7 14724.0 14759.3 15171.4 15243.5 15442.2 11239.7 11729.5 13950.2 14133.6 14562.8 11394.6 11459.4 12063.0 12334.8 12378.6 12197.1 12205.2 12234.8 12340.1 12367.9 05:36:23 INFO - PROCESS | 11954 | -------- Summary: end -------- 05:36:23 INFO - PROCESS | 11954 | 05:36:24 INFO - TEST-INFO | 11954: exit 0 05:36:24 INFO - TEST-OK | dromaeo_css | took 1111828ms 05:36:24 INFO - TEST-START | kraken 05:36:24 INFO - operating with platform_type : linux_ 05:36:24 INFO - Initialising browser for kraken test... 05:36:24 INFO - TEST-INFO | started process 12141 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXEgsKi/profile http://localhost:55366/getInfo.html) 05:36:28 INFO - PROCESS | 12141 | __metrics Screen width/height:1600/1200 05:36:28 INFO - PROCESS | 12141 | colorDepth:24 05:36:28 INFO - PROCESS | 12141 | Browser inner width/height: 1024/697 05:36:28 INFO - PROCESS | 12141 | __metrics 05:36:29 INFO - PROCESS | 12141 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:36:29 ERROR - PROCESS | 12141 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:36:30 INFO - TEST-INFO | 12141: exit 0 05:36:30 INFO - Browser initialized. 05:36:30 INFO - Running cycle 1/1 for kraken test... 05:36:30 INFO - TEST-INFO | started process 12232 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXEgsKi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:42 INFO - PROCESS | 12232 | RSS: Main: 141316096 05:36:42 INFO - PROCESS | 12232 | 05:36:44 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55366/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:45 INFO - PROCESS | 12232 | RSS: Main: 192090112 05:36:45 INFO - PROCESS | 12232 | 05:36:48 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55366/tests/kraken/driver.html?testName=audio-dft) 05:36:48 INFO - PROCESS | 12232 | RSS: Main: 189530112 05:36:48 INFO - PROCESS | 12232 | 05:36:51 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55366/tests/kraken/driver.html?testName=audio-fft) 05:36:52 INFO - PROCESS | 12232 | RSS: Main: 276566016 05:36:52 INFO - PROCESS | 12232 | 05:36:54 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55366/tests/kraken/driver.html?testName=audio-oscillator) 05:36:54 INFO - PROCESS | 12232 | RSS: Main: 184913920 05:36:54 INFO - PROCESS | 12232 | 05:36:57 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55366/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:57 INFO - PROCESS | 12232 | RSS: Main: 185495552 05:36:57 INFO - PROCESS | 12232 | 05:37:00 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55366/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:01 INFO - PROCESS | 12232 | RSS: Main: 220549120 05:37:01 INFO - PROCESS | 12232 | 05:37:04 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55366/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:05 INFO - PROCESS | 12232 | RSS: Main: 220155904 05:37:05 INFO - PROCESS | 12232 | 05:37:07 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55366/tests/kraken/driver.html?testName=json-parse-financial) 05:37:08 INFO - PROCESS | 12232 | RSS: Main: 219070464 05:37:08 INFO - PROCESS | 12232 | 05:37:09 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55366/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:10 INFO - PROCESS | 12232 | RSS: Main: 198053888 05:37:10 INFO - PROCESS | 12232 | 05:37:12 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:12 INFO - PROCESS | 12232 | RSS: Main: 198942720 05:37:12 INFO - PROCESS | 12232 | 05:37:14 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:15 INFO - PROCESS | 12232 | RSS: Main: 206934016 05:37:15 INFO - PROCESS | 12232 | 05:37:18 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:37:18 INFO - PROCESS | 12232 | RSS: Main: 210612224 05:37:18 INFO - PROCESS | 12232 | 05:37:21 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:37:21 INFO - PROCESS | 12232 | RSS: Main: 209682432 05:37:21 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | Cycle 1(1): loaded http://localhost:55366/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55366/tests/kraken/driver.html?testName=ai-astar) 05:37:23 INFO - PROCESS | 12232 | RSS: Main: 252723200 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | __start_tp_report 05:37:23 INFO - PROCESS | 12232 | _x_x_mozilla_page_load 05:37:23 INFO - PROCESS | 12232 | _x_x_mozilla_page_load_details 05:37:23 INFO - PROCESS | 12232 | |i|pagename|runs| 05:37:23 INFO - PROCESS | 12232 | |0;ai-astar;111;105;111;110;110;113;110;113;111;110 05:37:23 INFO - PROCESS | 12232 | |1;audio-beat-detection;152;132;128;174;131;127;149;131;155;126 05:37:23 INFO - PROCESS | 12232 | |2;audio-dft;160;162;160;161;160;161;161;162;163;177 05:37:23 INFO - PROCESS | 12232 | |3;audio-fft;76;75;75;113;74;76;76;75;114;74 05:37:23 INFO - PROCESS | 12232 | |4;audio-oscillator;87;84;118;84;84;84;130;85;85;130 05:37:23 INFO - PROCESS | 12232 | |5;imaging-gaussian-blur;117;116;117;117;116;117;116;117;118;116 05:37:23 INFO - PROCESS | 12232 | |6;imaging-darkroom;147;146;146;145;146;145;146;146;149;146 05:37:23 INFO - PROCESS | 12232 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 05:37:23 INFO - PROCESS | 12232 | |8;json-parse-financial;50;62;52;51;51;53;53;51;52;50 05:37:23 INFO - PROCESS | 12232 | |9;json-stringify-tinderbox;59;59;60;59;60;59;60;59;60;62 05:37:23 INFO - PROCESS | 12232 | |10;stanford-crypto-aes;79;77;76;76;77;77;77;78;116;76 05:37:23 INFO - PROCESS | 12232 | |11;stanford-crypto-ccm;127;120;128;117;115;127;123;120;130;122 05:37:23 INFO - PROCESS | 12232 | |12;stanford-crypto-pbkdf2;158;156;162;161;160;157;157;158;157;157 05:37:23 INFO - PROCESS | 12232 | |13;stanford-crypto-sha256-iterative;64;63;66;62;73;62;65;65;61;61 05:37:23 INFO - PROCESS | 12232 | __end_tp_report 05:37:23 INFO - PROCESS | 12232 | __start_cc_report 05:37:23 INFO - PROCESS | 12232 | _x_x_mozilla_cycle_collect,1533 05:37:23 INFO - PROCESS | 12232 | __end_cc_report 05:37:23 INFO - PROCESS | 12232 | __startTimestamp1470227843825__endTimestamp 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | ------- Summary: start ------- 05:37:23 INFO - PROCESS | 12232 | Number of tests: 14 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.22 (2.0%) stddev-sans-first:2.35 05:37:23 INFO - PROCESS | 12232 | Values: 111.0 105.0 111.0 110.0 110.0 113.0 110.0 113.0 111.0 110.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:140.50 stddev:16.12 (11.5%) stddev-sans-first:16.55 05:37:23 INFO - PROCESS | 12232 | Values: 152.0 132.0 128.0 174.0 131.0 127.0 149.0 131.0 155.0 126.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#2] audio-dft Cycles:10 Average:162.70 Median:161.50 stddev:5.12 (3.2%) stddev-sans-first:5.34 05:37:23 INFO - PROCESS | 12232 | Values: 160.0 162.0 160.0 161.0 160.0 161.0 161.0 162.0 163.0 177.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:16.20 (21.6%) stddev-sans-first:16.99 05:37:23 INFO - PROCESS | 12232 | Values: 76.0 75.0 75.0 113.0 74.0 76.0 76.0 75.0 114.0 74.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:84.00 stddev:20.23 (24.1%) stddev-sans-first:21.12 05:37:23 INFO - PROCESS | 12232 | Values: 87.0 84.0 118.0 84.0 84.0 84.0 130.0 85.0 85.0 130.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:37:23 INFO - PROCESS | 12232 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 118.0 116.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.14 (0.8%) stddev-sans-first:1.17 05:37:23 INFO - PROCESS | 12232 | Values: 147.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 149.0 146.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:37:23 INFO - PROCESS | 12232 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.00 stddev:3.50 (6.7%) stddev-sans-first:3.60 05:37:23 INFO - PROCESS | 12232 | Values: 50.0 62.0 52.0 51.0 51.0 53.0 53.0 51.0 52.0 50.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.95 (1.6%) stddev-sans-first:0.97 05:37:23 INFO - PROCESS | 12232 | Values: 59.0 59.0 60.0 59.0 60.0 59.0 60.0 59.0 60.0 62.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:77.00 stddev:12.37 (16.1%) stddev-sans-first:13.10 05:37:23 INFO - PROCESS | 12232 | Values: 79.0 77.0 76.0 76.0 77.0 77.0 77.0 78.0 116.0 76.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:125.00 stddev:5.00 (4.0%) stddev-sans-first:5.08 05:37:23 INFO - PROCESS | 12232 | Values: 127.0 120.0 128.0 117.0 115.0 127.0 123.0 120.0 130.0 122.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.30 Median:158.00 stddev:2.00 (1.3%) stddev-sans-first:2.12 05:37:23 INFO - PROCESS | 12232 | Values: 158.0 156.0 162.0 161.0 160.0 157.0 157.0 158.0 157.0 157.0 05:37:23 INFO - PROCESS | 12232 | 05:37:23 INFO - PROCESS | 12232 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:3.55 (5.5%) stddev-sans-first:3.77 05:37:23 INFO - PROCESS | 12232 | Values: 64.0 63.0 66.0 62.0 73.0 62.0 65.0 65.0 61.0 61.0 05:37:23 INFO - PROCESS | 12232 | -------- Summary: end -------- 05:37:23 INFO - PROCESS | 12232 | 05:37:24 INFO - TEST-INFO | 12232: exit 0 05:37:24 INFO - TEST-OK | kraken | took 60143ms 05:37:24 INFO - SUITE-END | took 1171s 05:37:24 INFO - Completed test suite (00:19:32) 05:37:24 INFO - javascript benchmark 05:37:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.223238655109, 3391.186115332321, 3395.083327501576, 3412.488126780984, 3429.6913277805006, 5564.471375219353, 5613.859653508664, 5614.9438505614935, 5634.0, 5636.1264004079385, 5511.641743286684, 5516.089845175551, 5547.750351234194, 5569.387367389584, 5572.4706152915505, 4894.290327902452, 4914.655974081807, 4921.778519966592, 4959.156943319645, 5023.598112151038, 4946.530079642428, 4960.7767650455635, 4967.850964471072, 4969.329140566031, 5002.1496345621335, 5010.949890501103, 5035.0, 5095.643304968654, 5105.132127538327, 5129.820456284031, 4987.925181122249, 5037.143685573461, 5086.364204474441, 5086.97456512717, 5099.4900509949075, 4888.266759985995, 4892.730899800509, 4954.851354459372, 4972.328735620681, 4995.375578052744, 5495.972520137374, 5544.0575102232715, 5549.6115271930985, 5551.084071128259, 5581.832545023656, 5761.366249712528, 5968.164456975984, 5984.970075149597, 6018.8495287617725, 6021.217241758587, 5936.7328470218945, 5954.523638108941, 5986.161937328778, 6085.300190478108, 6097.756089756404, 5652.406497317805, 5668.659880407147, 5671.999999999959, 5675.9432405675825, 5677.545796336283, 5924.881502369928, 6136.110264011693, 6174.44430001302, 6192.752289908443, 6195.070739389128, 5804.622699524517, 5875.089361149029, 5886.941130588725, 5946.851328716817, 5952.85117872048, 5990.5507086968655, 6025.6685882276015, 6027.517798576104, 6032.517398608188, 6039.305479869871, 5757.85605359861, 5964.135200395918, 5977.043673012297, 5977.970110149421, 5979.790707325223, 5843.649381037151, 5868.823935281948, 5905.1142328650185, 5937.406259374028, 5946.762129514857, 4481.215787237221, 4482.686211965131, 4483.125790470827, 4490.281554951192, 4510.345999830072, 3943.152222272225, 4017.5379831319187, 4017.819198136091, 4033.8991525212105, 4058.6144316289906, 5562.137868630369, 5621.353544342372, 5627.212190293362, 5662.348829884533, 5669.858253543694, 6032.758689652364, 6045.78839740607, 6049.21360223177, 6053.152558641794, 6093.542984276197, 5426.050441172779, 5583.748731307101, 5636.859078522988, 5679.744411501492, 5682.034054210794, 4985.501449854985, 5024.472430394829, 5030.346055012825, 5037.420696619935, 5065.0, 6206.193194884636, 6229.470495007975, 6248.125262463259, 6248.187735594343, 6257.5932564383165, 5804.071348584206, 5851.239338885917, 5908.881822363614, 6047.606905551125, 6058.394160584083, 6196.287426945959, 6203.844903877439, 6219.533534984804, 6219.906701399392, 6230.470410015199, 5922.881542369042, 5932.79235226765, 5937.139114828239, 5942.524598032233, 5970.044792833126, 5831.854203644943, 5898.026825573732, 5938.287405511366, 5974.880502390015, 6024.8795024100145, 5779.7110144493445, 5806.071028635398, 5823.301203855564, 5842.766289348378, 5846.210761547307, 5799.101139323411, 5834.15404766298, 5845.152452894222, 5848.853778655568, 5853.590248682552, 5593.300837395325, 5628.802991895264, 5649.180868774087, 5678.659280443187, 5679.176519404746, 5774.0761478163295, 5851.063829787214, 5864.648121112747, 5876.882462350814, 5879.882402352015, 5592.3009623797025, 5725.599208055396, 5727.341355744143, 5729.0, 5754.712264386848], "value": 5489.163041246255, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.7694737526051, 638.2723694987714, 657.0866495571154, 665.6871270502863, 670.7987603718883, 33156.0, 47269.527304726944, 48149.27776083353, 48189.27716084261, 48234.51765482344, 49724.50275497244, 50079.24881126777, 50123.49876501234, 50217.24674129882, 50308.74845625776, 8320.251177394033, 9174.40366376185, 9331.113544213324, 9332.160105590496, 10848.18638602108, 10630.096441802456, 11003.17476189289, 11048.06091482225, 11075.612353567627, 11085.224034317602, 11131.220814542985, 11168.218224724273, 11173.83239251412, 11175.944120279428, 11179.66461006171, 10976.890231097666, 11013.339199648046, 11046.944765276203, 11052.557897684082, 11056.447177641126, 10614.522346494427, 10635.734106647318, 10652.0, 10670.0, 10676.786464270748, 45417.2749635023, 45847.77076114598, 45886.77056614696, 45892.311615325794, 45911.0, 3561.8753343632848, 3576.2668652926086, 3596.496490491333, 3603.1172362771026, 3786.1291902862495, 34899.4765078524, 35230.82384588086, 35502.822485887664, 35640.0, 35911.82044089763, 4251.086016506466, 4254.298040823261, 4271.829126834923, 4282.5503322151035, 4284.228838809013, 385980.0, 386567.0, 387058.0, 387416.06291968643, 390206.0, 38430.309878308806, 64129.67935160294, 64723.35276647267, 64828.675856620415, 64828.67585662136, 42248.15503689909, 42413.36379954303, 42438.151236975085, 42468.57531424708, 42500.149996999884, 17431.0, 17532.737008944878, 17580.12099395025, 17584.208710607796, 17612.383566575365, 13126.474941002443, 14726.484573039892, 15037.097774133586, 15040.4207558207, 15313.693726125415, 9437.952810235905, 9442.716718498456, 9476.526173691287, 9487.14615684578, 9546.713598592054, 3600.927981440409, 3611.7832930024283, 3613.873514427035, 3637.9818100909324, 3644.107193737498, 1125.971850703739, 1152.475623591264, 1173.5188572685158, 1189.2032338333338, 1245.5640525816073, 33943.4908476373, 34557.65442345595, 34995.65004349975, 35133.0, 35237.47143792845, 1116.3023110555903, 1138.5502726423017, 1143.988560114405, 1247.1643998521013, 1258.8363512743285, 18140.546486337684, 18393.448196554124, 18449.81550184481, 18475.445736627924, 18636.72044919327, 577803.0, 580122.0, 580796.0, 581148.0, 582217.0, 66121.66939165369, 66475.66762166159, 66478.0, 66520.0, 66565.00152497717, 1481.7703255995339, 1499.1080307217405, 1511.8185817701947, 1513.1072667126448, 1526.8778497720157, 33778.0, 33995.0, 34957.30085398329, 35064.0, 35115.47326790049, 54415.455845441036, 55047.0, 55106.44893551013, 55133.724331378085, 55162.0, 425037.0, 434009.0, 434646.0, 435574.0, 435815.0, 27321.180364589094, 31832.52251216187, 32035.839820800746, 32292.0, 32691.34617307593, 50722.2391664118, 50976.74511627418, 51393.48606513887, 51578.48421515737, 51819.0, 42611.78694106633, 43379.566204337556, 43957.0, 44412.333814992155, 44855.0, 42478.78760606177, 42752.786236069864, 43262.134757305306, 43843.780781097164, 44069.559304406546, 42763.57236427596, 43048.354274685276, 43523.56476435195, 44298.0, 44583.55416445794, 38022.23955520929, 38892.0, 39034.804825976826, 39278.21443571056, 39341.803290983364, 29099.272518187216, 29256.0, 29388.0, 29523.409531809673, 29564.113076607737], "value": 20787.877221212766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.20030736682435, 390.72258696325605, 413.7931034482759, 414.46327006526536, 430.7157276197708, 14480.493182738606, 18759.718604220932, 19912.90043549784, 20342.08460619272, 20380.59238815223, 19488.41534753956, 19595.118219680113, 19805.504862378446, 19809.801901980976, 19863.30478433255, 5604.523615492678, 5843.707814609274, 5849.268841394825, 5857.209276747649, 5870.383609720981, 5649.661020338773, 5766.394528574502, 5809.651420914759, 5812.767489300444, 5813.157092221625, 4265.274903266437, 5997.940020599804, 6302.62184268943, 6303.149074874879, 6392.3927226913365, 6320.494360451153, 6347.492200623939, 6370.044493325991, 6373.490120790349, 6470.09418681385, 5486.890262194734, 5568.554515638739, 5581.386047534768, 5593.74828132735, 5604.579656525777, 23372.298831035096, 23472.765272347227, 23950.0, 24158.275251742474, 24394.51210975788, 2416.28719527738, 2504.3989442533843, 2552.770250677448, 2802.7477527022666, 2963.096255642028, 14553.563393098224, 14986.400543978229, 15139.848601514064, 15396.307166177548, 15537.922310388489, 1999.5001249687577, 2066.741657292838, 2106.7050612914204, 2125.9362219133295, 2151.741790985076, 59846.0, 65256.0, 72939.0, 80335.5983220086, 86690.99999999937, 10648.893511064945, 10689.358638481715, 11104.500297486633, 11119.110471162288, 11320.716982075513, 15289.694206115815, 15649.687006259812, 15655.138967356677, 15655.295511702001, 15669.0, 8754.51850148248, 8849.823003539894, 8855.91144088551, 8876.556172191365, 8881.955590222007, 8568.400211985227, 8612.8708069379, 8665.436746611576, 8720.389572729975, 8758.036615972238, 6842.384185423336, 6935.445164386837, 6944.583325000516, 6984.42520149303, 7084.893726594105, 1865.0581456364448, 1958.1482055305987, 1967.1836187982112, 1968.8031196880484, 1968.9704654430195, 839.8656215005569, 946.4510583861344, 1014.2899970021015, 1145.919785614999, 1146.340854008948, 19861.30485433003, 20002.39992800218, 20016.0, 20171.193152273743, 20239.291624793063, 1092.5629748100823, 1100.2298391126244, 1113.1317572293624, 1153.8038533449335, 1155.8092914668985, 10655.627053053106, 11329.0, 11455.083593312514, 11528.942355288169, 11774.823377649342, 533839.0, 549383.0, 558693.0, 564924.0, 566793.0, 96671.0, 98254.0, 99870.0, 100233.0, 101796.0, 1104.9116070714324, 1110.9611163609236, 1122.9775404491863, 1133.5919069135105, 1136.6760473265244, 17873.73189402186, 17931.73102403491, 18005.2797888083, 18182.72725909139, 18201.908990454962, 40560.0, 41219.38170927498, 42139.1572168561, 42448.57551424446, 42919.14161716686, 61231.693841530505, 61476.69261653842, 61979.690101549204, 62385.0, 63306.0, 7747.186545412876, 8105.878411823709, 8281.917180828355, 8518.787030324043, 8691.348148888732, 12982.675433113869, 13780.793288100887, 13838.723225535232, 13846.238456884968, 13998.580042598738, 12075.637730868088, 12084.516619335129, 12196.57311994076, 12210.511579537075, 12592.74814503723, 11466.541338346373, 11685.707857303636, 11895.464704088165, 12208.511659533518, 12569.371531423576, 11331.150163737588, 12111.212771169845, 12179.939100304442, 12198.817017744563, 12811.743765124458, 8859.468431894107, 8912.821743565222, 8939.73180804577, 9164.266858651425, 9181.357304988587, 11225.101991840469, 11635.41822908868, 11746.882531174579, 12028.819567706667, 12515.0], "value": 9775.5175276967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.7055133146384, 1576.6610178811554, 1600.503843808419, 1788.5886246163382, 1928.8456923446117, 8701.869471957925, 9080.04659510751, 9080.591373388197, 9391.060893910606, 9403.670871519498, 9079.91041075072, 9271.026542213054, 9277.489738064403, 9278.396904201221, 9284.303677224218, 2389.7132344118686, 2411.3007227903972, 2411.9276421707377, 2418.6855708757835, 2426.417659761662, 2382.642603609455, 2406.0496104038893, 2406.0736616402723, 2407.4823912858733, 2418.2745176446997, 2383.8200090955, 2403.735589085199, 2404.1345115758313, 2428.854268743872, 2437.7196622388474, 2333.673285739998, 2345.4488195274184, 2368.763123687626, 2373.6439534070028, 2375.0262392419113, 2276.3398614401804, 2324.4886125052462, 2324.7442781294044, 2326.255598208574, 2332.8367014309006, 8062.798430039237, 8264.83470330596, 9185.265178785681, 9350.766230844216, 9353.158215760614, 5182.844514664566, 5323.600729945269, 5334.119870221409, 5435.918461223046, 5438.864028399282, 3504.7196224301993, 3522.3131489359585, 3563.1626567756816, 3567.4113771227717, 3618.5838628557526, 1134.1947217475602, 1147.9311241325547, 1151.851796399717, 1151.995527781654, 1152.0070278418384, 13027.674308142277, 13102.0, 13132.737345253137, 13143.605691829165, 13162.0, 4722.6221902247735, 4749.715017098985, 4793.736344501016, 4881.316615673809, 4910.48439913811, 11043.779124417466, 11065.004149626573, 11068.723281918017, 11068.889311106946, 11071.225014248925, 5454.7818087276855, 5457.645253058538, 5458.781648734006, 5460.781568737286, 5463.235147079412, 5018.347614810052, 5073.34046573943, 5145.562627176657, 5165.354330708661, 5169.948300517022, 4013.8996525087105, 4062.5937406259136, 4118.073433477443, 4136.565660605654, 4163.937540936889, 1253.529926277646, 1253.6991122130623, 1259.7354555543254, 1280.5774094548776, 1284.5118854835102, 1044.6918159143072, 1057.3021805608416, 1070.309650275568, 1079.3577821196532, 1080.9729756755987, 6216.595921265103, 6393.424591786761, 6399.9120149573755, 6429.164208652939, 6485.805425837232, 1014.2950649298666, 1027.6300531808542, 1027.825269704137, 1075.7310672331917, 1116.24749143845, 4367.475902891673, 4425.446819147606, 4470.441194850644, 4500.369948207253, 4522.095580883836, 11496.655100347003, 11886.049116070866, 11934.0, 11940.641780746591, 11952.641420757218, 11469.25449845774, 11813.586524471602, 11836.822447663291, 11838.348890811088, 11889.643310700692, 8576.656933722581, 8616.267617252479, 8822.823543529223, 8872.8669069964, 8962.731118066598, 6305.527085468517, 6384.7446102157255, 6394.1687580614225, 6504.1219435375615, 6584.901226481702, 10579.62971296001, 10749.89250107489, 10836.13310935139, 10845.620403285848, 11070.114390848552, 10674.199435042558, 11013.118950484102, 11016.0, 11166.944165279121, 11178.0498657615, 8331.333493320399, 8331.916680833114, 8433.620487077973, 8458.619362128842, 8471.449355791854, 9027.909720902708, 9038.0, 9104.13510716481, 9217.539123043955, 9229.630814767603, 8121.106678265504, 8129.146439623754, 8155.877661835196, 8391.664333426594, 8431.40980131385, 7739.187385324685, 8012.198780121941, 8019.478733882279, 8087.47431416956, 8208.09710931785, 7278.163011253773, 7321.048263725682, 7360.153582337885, 7365.300296471826, 7366.521176123535, 5476.808311709071, 5507.366652834975, 5563.693996830213, 5609.270794796814, 5673.659580425187, 4924.162892308244, 5420.403755586961, 5492.752826122914, 5591.832245032656, 5692.00389931752], "value": 4899.391970024314, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.83201231396606, 344.5314372453463, 351.7432274439658, 356.2820915854552, 359.28322995623734, 5471.835844924653, 5572.4148964358665, 5582.469665381787, 5606.495415412623, 5616.831495055149, 5463.262459567957, 5509.0, 5557.694326812024, 5574.637648552841, 5580.302462192226, 3866.4340941918567, 4099.622219112922, 4108.732932359395, 4236.301010333292, 4283.7429754214845, 4079.1637714268654, 4211.136716973013, 4269.502341130443, 4269.914601707964, 4270.551592082833, 4167.249895018896, 4176.770277634729, 4187.853425130121, 4215.599518045796, 4227.513835908879, 4034.09232922593, 4134.9999999999845, 4217.650351887397, 4224.725392849455, 4239.554846741079, 3746.83139258734, 3770.2459508098214, 3782.8108594570435, 3785.0, 3788.867389641377, 5246.982984783765, 5264.15773476242, 5297.894042119175, 5310.468953104697, 5327.973360133214, 4814.181589129821, 4821.662483626147, 4856.247281671346, 4864.659473836833, 4893.241547560134, 5612.635178713412, 5662.009148399014, 5666.320041594994, 5673.6879471629045, 5689.236336735626, 4175.457190565237, 4183.895402614959, 4199.202151591188, 4200.873973780792, 4212.431321771583, 5593.468620481048, 5682.971585142131, 5774.7295594969455, 5850.162671104511, 5867.559933005042, 4246.299360605465, 4253.298205796009, 4271.380649805823, 4274.380214868826, 4278.978605107017, 4302.666173486166, 4342.5874541918465, 4348.999460248321, 4363.323684828857, 4369.169857727022, 4614.307853821953, 4812.0, 4907.975460122748, 4923.236898280772, 4949.727764972889, 5693.430656934273, 5715.228444159985, 5731.77072917087, 5753.33836608787, 5816.185733997244, 1453.7570377327445, 1471.720373129102, 1473.955781326562, 1475.5573328001556, 1478.7652310321002, 914.7833381602453, 931.2131249094505, 943.5942544705842, 946.0350442548562, 956.6747305916022, 4931.5561599455505, 4955.628327875424, 4971.0, 4982.60139188864, 4993.300937868701, 5253.894922101536, 5287.206918962186, 5318.574514038945, 5327.348521958205, 5348.465153484621, 5032.245163225472, 5040.344755181876, 5058.797648094035, 5271.604629652715, 5683.0, 3118.6257649082254, 3149.5590617313596, 3173.8889138880027, 3186.4423725847882, 3217.4691175956173, 6078.45293923549, 6131.608672617683, 6136.514711541833, 6171.907421388682, 6172.938270617326, 5781.6241944274325, 6006.0, 6021.862238905213, 6025.548083893726, 6051.697415129226, 6109.327973922865, 6150.7232174552255, 6171.783987560414, 6176.0, 6190.969045154746, 5637.913971830285, 5941.316748573797, 5942.0, 5980.880382392241, 5993.460588547052, 5823.388544202967, 5860.560457965755, 5863.208466857091, 5880.50015748649, 5894.911576326444, 5855.560832937632, 5973.104034394875, 6118.235220597426, 6133.975787990042, 6140.385961403824, 4135.407867970793, 4148.605882441163, 4162.2716024696165, 4183.958160418357, 4192.098698779777, 5800.738966746422, 5910.852228694317, 5982.760689572368, 6006.969965150146, 6017.969910150421, 5708.686022268649, 5718.1708652246025, 5741.8564535886935, 5754.597178197653, 5773.624714393551, 5728.226689396878, 5736.4550367715, 5741.5119714824705, 5783.132530120515, 5783.16144159103, 5476.2607048048, 5685.232493613475, 5701.942980570141, 5709.571782116275, 5711.114777209539, 5783.248177736868, 5808.5353171747, 5809.738561764646, 5826.475617194473, 5838.2702162229725, 5423.83387571674, 5582.469665381782, 5708.942910571007, 5726.455986681259, 5729.281215635376], "value": 4350.164016732746, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.0641816983285, 781.5232708048089, 815.6085079161999, 871.3726117195619, 911.535117090284, 16013.83986160138, 16218.513444596652, 16300.0, 16516.174191290447, 16637.0, 14923.701525969474, 14943.178125203136, 15067.17130557819, 15104.169270690109, 15114.6977060459, 731.6707481633259, 881.0792721605937, 902.0528445132617, 903.9593218305159, 916.9633214671405, 895.6104094718818, 899.4962820820328, 902.7066203483861, 905.6784841381314, 905.8777065096193, 944.3719926249037, 949.9857502137439, 954.9379290346105, 956.2827879090682, 956.9377990430635, 777.8055486128468, 921.5438358012793, 952.7618095476131, 953.7472569768983, 954.0459540459541, 704.4082970304917, 704.8766465868453, 705.048184950318, 705.1045172630782, 705.2735682247294, 14878.0, 14880.62798430037, 14970.101793892401, 15019.699606007818, 15022.924885375613, 14285.57143285703, 14373.281335933161, 14702.485413010494, 14730.55808325752, 14894.329755160936, 12574.559890403792, 12781.61655150347, 12839.935800321033, 12950.158239714483, 13013.86986130136, 4639.373684552576, 4687.648426368036, 4714.504976977402, 4745.738984355859, 4747.430308362984, 74855.0, 75377.0, 75778.86331705029, 76347.23652763457, 76820.0, 11123.388213648326, 11157.66527004191, 11236.550537978554, 11299.717507062389, 11561.248518846249, 19828.70256946147, 19862.0, 19902.50243743918, 20059.39821805348, 20091.698624520643, 8364.079951205362, 8439.535825529654, 8521.829563408697, 8544.786380340542, 8560.630299152106, 5140.485951404829, 5153.665011774223, 5154.098032844237, 5160.612954028463, 5184.688918664892, 3503.649635036476, 3614.584322802859, 3617.638236176414, 3621.7464777465852, 3622.347977364047, 634.6255709131634, 651.837040739815, 659.9043138744855, 674.089978528982, 680.9693563789542, 615.4030590327375, 639.341478277375, 681.8159097043771, 701.6176183979743, 707.4976766495743, 9410.200132988635, 9459.19596834263, 9470.621175152917, 9501.382410143318, 9539.713808585753, 675.2572170612287, 734.1447213995635, 745.671904362079, 749.3480671815446, 777.9183185765527, 4648.674592778473, 4686.0, 4712.717236965793, 4830.275458681226, 4834.250691142878, 68923.3107668917, 69086.65456672786, 69402.65298673576, 86397.56801215954, 113663.0, 76065.23934760582, 97022.0, 97272.51363743136, 98648.50675746576, 99172.0, 89324.5533772327, 89659.55170224106, 89725.0, 89768.0, 90352.5482372584, 9140.6800761973, 9178.954105229432, 9225.108763206961, 9288.11762882525, 9332.39339442934, 52325.21512177397, 53158.73420632872, 53398.0, 53423.19865202103, 54812.45187548233, 69142.65428672824, 74174.0, 74908.0, 76065.61967190128, 78556.0, 11745.941270293593, 11863.0, 11969.64091077269, 12468.251904885736, 12820.487180512675, 12604.432800524182, 13611.931940340632, 14480.85519144795, 15071.0, 15200.543983680507, 14490.85509144895, 14732.189729564549, 15455.381784728484, 15664.686706266039, 15939.681206376039, 14724.0, 14759.262036897897, 15171.3519419885, 15243.466478673194, 15442.2278886053, 11239.66281011571, 11729.530818767154, 13950.232737199145, 14133.575992720234, 14562.781558276423, 11394.601188958346, 11459.369734664673, 12063.0, 12334.81497777552, 12378.566750163702, 12197.146199765932, 12205.20666156697, 12234.816477753018, 12340.136190466581, 12367.876321236672], "value": 7738.1324829270825, "unit": "score"}], "name": "dromaeo_css", "value": 7541.607392289947, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 110.0, 110.0, 113.0, 110.0, 113.0, 111.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 132.0, 128.0, 174.0, 131.0, 127.0, 149.0, 131.0, 155.0, 126.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 161.0, 160.0, 161.0, 161.0, 162.0, 163.0, 177.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 113.0, 74.0, 76.0, 76.0, 75.0, 114.0, 74.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 118.0, 84.0, 84.0, 84.0, 130.0, 85.0, 85.0, 130.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 118.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 149.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 62.0, 52.0, 51.0, 51.0, 53.0, 53.0, 51.0, 52.0, 50.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 60.0, 59.0, 60.0, 62.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 76.0, 76.0, 77.0, 77.0, 77.0, 78.0, 116.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 120.0, 128.0, 117.0, 115.0, 127.0, 123.0, 120.0, 130.0, 122.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 156.0, 162.0, 161.0, 160.0, 157.0, 157.0, 158.0, 157.0, 157.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 62.0, 73.0, 62.0, 65.0, 65.0, 61.0, 61.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1490.0000000000002, "alertThreshold": 2.0}]} 05:37:25 INFO - Return code: 0 05:37:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:37:25 ERROR - # TBPL SUCCESS # 05:37:25 INFO - Running post-action listener: _resource_record_post_action 05:37:25 INFO - ##### 05:37:25 INFO - ##### Finished run-tests step (success) 05:37:25 INFO - ##### 05:37:25 INFO - Running post-run listener: _resource_record_post_run 05:37:25 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 28672; Write bytes: 456581120; Read time: 20; Write time: 392544 05:37:25 INFO - TinderboxPrint: CPU usage
13.0% 05:37:25 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 20 05:37:25 INFO - TinderboxPrint: I/O write bytes / time
456,581,120 / 392,544 05:37:25 INFO - TinderboxPrint: CPU idle
8,232.9 (86.9%) 05:37:25 INFO - TinderboxPrint: CPU user
1,192.7 (12.6%) 05:37:25 INFO - TinderboxPrint: Swap in / out
0 / 0 05:37:25 INFO - install - Wall time: 13s; CPU: 14.0%; Read bytes: 0; Write bytes: 235020288; Read time: 0; Write time: 263200 05:37:25 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 221204480; Read time: 12; Write time: 129320 05:37:25 INFO - Running post-run listener: _upload_blobber_files 05:37:25 INFO - Blob upload gear active. 05:37:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:37:25 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:37:25 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:37:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:37:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:37:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:26 INFO - (blobuploader) - INFO - Done attempting. 05:37:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:37:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:37:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:27 INFO - (blobuploader) - INFO - Done attempting. 05:37:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:37:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:37:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:32 INFO - (blobuploader) - INFO - Done attempting. 05:37:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:32 INFO - Return code: 0 05:37:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b89a872fe8e73b64172727c2fe9c8a80b995a002891af4327bdadc1957b5cfd34d27918fe89a853b37dc54ee9020de51773a073441f61acff61df29002e4b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28cc0dd8aa13dfabde776559f15e81ea9c08a6a0b9667949e73a37d0d2035b31b1d65fc2605fa44e2c7a6d8e255e6e16e8c5d20d2d553ac0be487219a962b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34069b39e0e4c5cd8b242ae91ed9cef1b39bae1e24ccc29a497c57175e0afba2665fcda13ec4d5fc26cf505e789f142221814c8a9d4bf60ac8b5a4118f934df6"} 05:37:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:32 INFO - Contents: 05:37:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b89a872fe8e73b64172727c2fe9c8a80b995a002891af4327bdadc1957b5cfd34d27918fe89a853b37dc54ee9020de51773a073441f61acff61df29002e4b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28cc0dd8aa13dfabde776559f15e81ea9c08a6a0b9667949e73a37d0d2035b31b1d65fc2605fa44e2c7a6d8e255e6e16e8c5d20d2d553ac0be487219a962b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34069b39e0e4c5cd8b242ae91ed9cef1b39bae1e24ccc29a497c57175e0afba2665fcda13ec4d5fc26cf505e789f142221814c8a9d4bf60ac8b5a4118f934df6"} 05:37:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:32 INFO - Copying logs to upload dir... 05:37:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.288911 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-03 05:37:32.050951) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 05:37:32.054789) ========= 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=0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354 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-Beta/sha512/8b89a872fe8e73b64172727c2fe9c8a80b995a002891af4327bdadc1957b5cfd34d27918fe89a853b37dc54ee9020de51773a073441f61acff61df29002e4b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28cc0dd8aa13dfabde776559f15e81ea9c08a6a0b9667949e73a37d0d2035b31b1d65fc2605fa44e2c7a6d8e255e6e16e8c5d20d2d553ac0be487219a962b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34069b39e0e4c5cd8b242ae91ed9cef1b39bae1e24ccc29a497c57175e0afba2665fcda13ec4d5fc26cf505e789f142221814c8a9d4bf60ac8b5a4118f934df6"} build_url:https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/IMe43gD-Qym5oAJPA5S_TQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b89a872fe8e73b64172727c2fe9c8a80b995a002891af4327bdadc1957b5cfd34d27918fe89a853b37dc54ee9020de51773a073441f61acff61df29002e4b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28cc0dd8aa13dfabde776559f15e81ea9c08a6a0b9667949e73a37d0d2035b31b1d65fc2605fa44e2c7a6d8e255e6e16e8c5d20d2d553ac0be487219a962b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34069b39e0e4c5cd8b242ae91ed9cef1b39bae1e24ccc29a497c57175e0afba2665fcda13ec4d5fc26cf505e789f142221814c8a9d4bf60ac8b5a4118f934df6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 05:37:32.080531) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:37:32.080830) ========= 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=0e2a256762fed810e2f0f810000001b3-1470225588.913302-628696354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 05:37:32.166137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 05:37:32.166434) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 05:37:32.166714) ========= ========= Total master_lag: 0.18 =========