builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1465104037.65 results: success (0) buildid: 20160604193055 builduid: c8b20be8f91f4c3d9502481262476f3d revision: 4f5df0a09686af630e023c17e965977d9aa1c38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.652981) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.653551) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.653875) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.668631) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.668980) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-04 22:20:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 214M=0s 2016-06-04 22:20:38 (214 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174924 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.894785) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.895121) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022864 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.935267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:37.935645) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4f5df0a09686af630e023c17e965977d9aa1c38a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4f5df0a09686af630e023c17e965977d9aa1c38a --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-04 22:20:38,426 truncating revision to first 12 chars 2016-06-04 22:20:38,426 Setting DEBUG logging. 2016-06-04 22:20:38,426 attempt 1/10 2016-06-04 22:20:38,426 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/4f5df0a09686?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 22:20:38,887 unpacking tar archive at: mozilla-inbound-4f5df0a09686/testing/mozharness/ program finished with exit code 0 elapsedTime=0.808943 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:38.758543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:38.758871) ========= script_repo_revision: 4f5df0a09686af630e023c17e965977d9aa1c38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:38.759334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:38.759669) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:38.769487) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-04 22:20:38.769815) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:20:39 INFO - MultiFileLogger online at 20160604 22:20:39 in /builds/slave/test 22:20:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:20:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:20:39 INFO - {'append_to_log': False, 22:20:39 INFO - 'base_work_dir': '/builds/slave/test', 22:20:39 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:20:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:20:39 INFO - 'branch': 'Mozilla-Inbound', 22:20:39 INFO - 'buildbot_json_path': 'buildprops.json', 22:20:39 INFO - 'config_files': ('talos/linux_config.py',), 22:20:39 INFO - 'default_actions': ('clobber', 22:20:39 INFO - 'read-buildbot-config', 22:20:39 INFO - 'download-and-extract', 22:20:39 INFO - 'populate-webroot', 22:20:39 INFO - 'create-virtualenv', 22:20:39 INFO - 'install', 22:20:39 INFO - 'run-tests'), 22:20:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:20:39 INFO - 'download_minidump_stackwalk': True, 22:20:39 INFO - 'download_symbols': 'ondemand', 22:20:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:20:39 INFO - 'tooltool.py': '/tools/tooltool.py', 22:20:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:20:39 INFO - '/tools/misc-python/virtualenv.py')}, 22:20:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:20:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:20:39 INFO - 'installer_path': 'installer.exe', 22:20:39 INFO - 'log_level': 'info', 22:20:39 INFO - 'log_name': 'talos', 22:20:39 INFO - 'log_to_console': True, 22:20:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:20:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:20:39 INFO - 'opt_config_files': (), 22:20:39 INFO - 'pip_index': False, 22:20:39 INFO - 'sps_profile': False, 22:20:39 INFO - 'sps_profile_interval': 0, 22:20:39 INFO - 'suite': 'dromaeojs', 22:20:39 INFO - 'system_bits': '32', 22:20:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:20:39 INFO - 'title': 'talos-linux64-ix-033', 22:20:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:20:39 INFO - 'use_talos_json': True, 22:20:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:20:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:20:39 INFO - 'work_dir': 'build'} 22:20:39 INFO - ##### 22:20:39 INFO - ##### Running clobber step. 22:20:39 INFO - ##### 22:20:39 INFO - Running pre-action listener: _resource_record_pre_action 22:20:39 INFO - Running main action method: clobber 22:20:39 INFO - rmtree: /builds/slave/test/build 22:20:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:20:39 INFO - Running post-action listener: _resource_record_post_action 22:20:39 INFO - ##### 22:20:39 INFO - ##### Finished clobber step (success) 22:20:39 INFO - ##### 22:20:39 INFO - ##### 22:20:39 INFO - ##### Running read-buildbot-config step. 22:20:39 INFO - ##### 22:20:39 INFO - Running pre-action listener: _resource_record_pre_action 22:20:39 INFO - Running main action method: read_buildbot_config 22:20:39 INFO - Using buildbot properties: 22:20:39 INFO - { 22:20:39 INFO - "project": "", 22:20:39 INFO - "product": "firefox", 22:20:39 INFO - "script_repo_revision": "production", 22:20:39 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:20:39 INFO - "repository": "", 22:20:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:20:39 INFO - "buildid": "20160604193055", 22:20:39 INFO - "pgo_build": "True", 22:20:39 INFO - "basedir": "/builds/slave/test", 22:20:39 INFO - "buildnumber": 1126, 22:20:39 INFO - "slavename": "talos-linux64-ix-033", 22:20:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:20:39 INFO - "platform": "ubuntu64_hw", 22:20:39 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:20:39 INFO - "branch": "mozilla-inbound", 22:20:39 INFO - "repo_path": "integration/mozilla-inbound", 22:20:39 INFO - "revision": "4f5df0a09686af630e023c17e965977d9aa1c38a", 22:20:39 INFO - "stage_platform": "linux64-pgo", 22:20:39 INFO - "builduid": "c8b20be8f91f4c3d9502481262476f3d", 22:20:39 INFO - "slavebuilddir": "test" 22:20:39 INFO - } 22:20:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:20:39 INFO - Running post-action listener: _resource_record_post_action 22:20:39 INFO - ##### 22:20:39 INFO - ##### Finished read-buildbot-config step (success) 22:20:39 INFO - ##### 22:20:39 INFO - ##### 22:20:39 INFO - ##### Running download-and-extract step. 22:20:39 INFO - ##### 22:20:39 INFO - Running pre-action listener: _resource_record_pre_action 22:20:39 INFO - Running main action method: download_and_extract 22:20:39 INFO - mkdir: /builds/slave/test/build/tests 22:20:39 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')]} 22:20:39 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:20:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:20:49 INFO - Downloaded 1361 bytes. 22:20:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:49 INFO - Using the following test package requirements: 22:20:49 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:20:49 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:20:49 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:20:49 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'jsshell-linux-x86_64.zip'], 22:20:49 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:20:49 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:20:49 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:20:49 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:20:49 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:20:49 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:20:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:49 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:20:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:20:50 INFO - Downloaded 24441808 bytes. 22:20:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:20:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:20:52 INFO - Return code: 0 22:20:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:52 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:20:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:21:01 INFO - Downloaded 24441808 bytes. 22:21:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:21:02 INFO - Return code: 0 22:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:02 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:21:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:21:04 INFO - Downloaded 10833369 bytes. 22:21:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:21:04 INFO - Return code: 0 22:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:04 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:21:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:21:05 INFO - Downloaded 59183030 bytes. 22:21:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:05 INFO - mkdir: /builds/slave/test/properties 22:21:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:21:05 INFO - Writing to file /builds/slave/test/properties/build_url 22:21:05 INFO - Contents: 22:21:05 INFO - build_url:https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:06 INFO - Running post-action listener: _resource_record_post_action 22:21:06 INFO - Running post-action listener: set_extra_try_arguments 22:21:06 INFO - ##### 22:21:06 INFO - ##### Finished download-and-extract step (success) 22:21:06 INFO - ##### 22:21:06 INFO - ##### 22:21:06 INFO - ##### Running populate-webroot step. 22:21:06 INFO - ##### 22:21:06 INFO - Running pre-action listener: _resource_record_pre_action 22:21:06 INFO - Running main action method: populate_webroot 22:21:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:21:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:21:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:21:06 INFO - '--tppagecycles', 22:21:06 INFO - '7'], 22:21:06 INFO - 'tests': ['tsvgm']}}, 22:21:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:21:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:21:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:21:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:21:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['damp', 'tps']}, 22:21:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'tests': ['damp', 'tps']}, 22:21:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['dromaeo_dom']}, 22:21:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:21:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['a11yr', 22:21:06 INFO - 'ts_paint', 22:21:06 INFO - 'tpaint', 22:21:06 INFO - 'sessionrestore', 22:21:06 INFO - 'sessionrestore_no_auto_restore', 22:21:06 INFO - 'tabpaint']}, 22:21:06 INFO - 'other-e10s': {'tests': ['a11yr', 22:21:06 INFO - 'ts_paint', 22:21:06 INFO - 'tpaint', 22:21:06 INFO - 'sessionrestore', 22:21:06 INFO - 'sessionrestore_no_auto_restore', 22:21:06 INFO - 'tabpaint']}, 22:21:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:21:06 INFO - 'ts_paint', 22:21:06 INFO - 'tpaint', 22:21:06 INFO - 'sessionrestore', 22:21:06 INFO - 'sessionrestore_no_auto_restore', 22:21:06 INFO - 'tabpaint']}, 22:21:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:21:06 INFO - 'ts_paint', 22:21:06 INFO - 'tpaint', 22:21:06 INFO - 'sessionrestore', 22:21:06 INFO - 'sessionrestore_no_auto_restore', 22:21:06 INFO - 'tabpaint']}, 22:21:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['a11yr', 22:21:06 INFO - 'ts_paint', 22:21:06 INFO - 'tpaint', 22:21:06 INFO - 'sessionrestore', 22:21:06 INFO - 'sessionrestore_no_auto_restore', 22:21:06 INFO - 'tabpaint']}, 22:21:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['a11yr', 22:21:06 INFO - 'ts_paint', 22:21:06 INFO - 'tpaint', 22:21:06 INFO - 'sessionrestore', 22:21:06 INFO - 'sessionrestore_no_auto_restore', 22:21:06 INFO - 'tabpaint']}, 22:21:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['tsvgx', 22:21:06 INFO - 'tsvgr_opacity', 22:21:06 INFO - 'tart', 22:21:06 INFO - 'tscrollx', 22:21:06 INFO - 'cart']}, 22:21:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:21:06 INFO - 'tsvgr_opacity', 22:21:06 INFO - 'tart', 22:21:06 INFO - 'tscrollx', 22:21:06 INFO - 'cart']}, 22:21:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'talos_options': ['--disable-e10s'], 22:21:06 INFO - 'tests': ['tp5o']}, 22:21:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'tests': ['tp5o']}, 22:21:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'talos_options': ['--disable-e10s', 22:21:06 INFO - '--xperf_path', 22:21:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:06 INFO - 'tests': ['tp5n']}, 22:21:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:06 INFO - 'talos_options': ['--xperf_path', 22:21:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:06 INFO - 'tests': ['tp5n']}}, 22:21:06 INFO - 'talos.zip': {'path': '', 22:21:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:21:06 INFO - Running post-action listener: _resource_record_post_action 22:21:06 INFO - ##### 22:21:06 INFO - ##### Finished populate-webroot step (success) 22:21:06 INFO - ##### 22:21:06 INFO - ##### 22:21:06 INFO - ##### Running create-virtualenv step. 22:21:06 INFO - ##### 22:21:06 INFO - Running pre-action listener: _resource_record_pre_action 22:21:06 INFO - Running main action method: create_virtualenv 22:21:06 INFO - Creating virtualenv /builds/slave/test/build/venv 22:21:06 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 22:21:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:21:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:21:06 INFO - Using real prefix '/usr' 22:21:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:21:06 INFO - Installing distribute.............................................................................................................................................................................................done. 22:21:08 INFO - Installing pip.................done. 22:21:08 INFO - Return code: 0 22:21:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:21:08 INFO - Using env: {'DISPLAY': ':0', 22:21:08 INFO - 'HOME': '/home/cltbld', 22:21:08 INFO - 'LANG': 'en_US.UTF-8', 22:21:08 INFO - 'LANGUAGE': 'en_US:en', 22:21:08 INFO - 'LOGNAME': 'cltbld', 22:21:08 INFO - 'MAIL': '/var/mail/cltbld', 22:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:08 INFO - 'MOZ_NO_REMOTE': '1', 22:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:08 INFO - 'NO_EM_RESTART': '1', 22:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:08 INFO - 'PWD': '/builds/slave/test', 22:21:08 INFO - 'SHELL': '/bin/bash', 22:21:08 INFO - 'SHLVL': '1', 22:21:08 INFO - 'TERM': 'linux', 22:21:08 INFO - 'TMOUT': '86400', 22:21:08 INFO - 'USER': 'cltbld', 22:21:08 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:08 INFO - '_': '/tools/buildbot/bin/python'} 22:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:09 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 22:21:09 INFO - Cleaning up... 22:21:09 INFO - Return code: 0 22:21:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:09 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:09 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 22:21:09 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 22:21:09 INFO - Using env: {'DISPLAY': ':0', 22:21:09 INFO - 'HOME': '/home/cltbld', 22:21:09 INFO - 'LANG': 'en_US.UTF-8', 22:21:09 INFO - 'LANGUAGE': 'en_US:en', 22:21:09 INFO - 'LOGNAME': 'cltbld', 22:21:09 INFO - 'MAIL': '/var/mail/cltbld', 22:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:09 INFO - 'MOZ_NO_REMOTE': '1', 22:21:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:09 INFO - 'NO_EM_RESTART': '1', 22:21:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:09 INFO - 'PWD': '/builds/slave/test', 22:21:09 INFO - 'SHELL': '/bin/bash', 22:21:09 INFO - 'SHLVL': '1', 22:21:09 INFO - 'TERM': 'linux', 22:21:09 INFO - 'TMOUT': '86400', 22:21:09 INFO - 'USER': 'cltbld', 22:21:09 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:09 INFO - '_': '/tools/buildbot/bin/python'} 22:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:09 INFO - Downloading/unpacking psutil>=3.1.1 22:21:09 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 22:21:09 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 22:21:09 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 22:21:09 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 22:21:09 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 22:21:09 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 22:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:11 INFO - Installing collected packages: psutil 22:21:11 INFO - Running setup.py install for psutil 22:21:11 INFO - building 'psutil._psutil_linux' extension 22:21:11 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 22:21:11 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 22:21:11 INFO - building 'psutil._psutil_posix' extension 22:21:11 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 22:21:11 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 22:21:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:11 INFO - Successfully installed psutil 22:21:11 INFO - Cleaning up... 22:21:11 INFO - Return code: 0 22:21:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:11 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:11 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 22:21:11 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 22:21:11 INFO - Using env: {'DISPLAY': ':0', 22:21:11 INFO - 'HOME': '/home/cltbld', 22:21:11 INFO - 'LANG': 'en_US.UTF-8', 22:21:11 INFO - 'LANGUAGE': 'en_US:en', 22:21:11 INFO - 'LOGNAME': 'cltbld', 22:21:11 INFO - 'MAIL': '/var/mail/cltbld', 22:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:11 INFO - 'MOZ_NO_REMOTE': '1', 22:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:11 INFO - 'NO_EM_RESTART': '1', 22:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:11 INFO - 'PWD': '/builds/slave/test', 22:21:11 INFO - 'SHELL': '/bin/bash', 22:21:11 INFO - 'SHLVL': '1', 22:21:11 INFO - 'TERM': 'linux', 22:21:11 INFO - 'TMOUT': '86400', 22:21:11 INFO - 'USER': 'cltbld', 22:21:11 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:11 INFO - '_': '/tools/buildbot/bin/python'} 22:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:14 INFO - Installing collected packages: mozsystemmonitor 22:21:14 INFO - Running setup.py install for mozsystemmonitor 22:21:14 INFO - Successfully installed mozsystemmonitor 22:21:14 INFO - Cleaning up... 22:21:14 INFO - Return code: 0 22:21:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:14 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:14 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 22:21:14 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 22:21:14 INFO - Using env: {'DISPLAY': ':0', 22:21:14 INFO - 'HOME': '/home/cltbld', 22:21:14 INFO - 'LANG': 'en_US.UTF-8', 22:21:14 INFO - 'LANGUAGE': 'en_US:en', 22:21:14 INFO - 'LOGNAME': 'cltbld', 22:21:14 INFO - 'MAIL': '/var/mail/cltbld', 22:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:14 INFO - 'MOZ_NO_REMOTE': '1', 22:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:14 INFO - 'NO_EM_RESTART': '1', 22:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:14 INFO - 'PWD': '/builds/slave/test', 22:21:14 INFO - 'SHELL': '/bin/bash', 22:21:14 INFO - 'SHLVL': '1', 22:21:14 INFO - 'TERM': 'linux', 22:21:14 INFO - 'TMOUT': '86400', 22:21:14 INFO - 'USER': 'cltbld', 22:21:14 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:14 INFO - '_': '/tools/buildbot/bin/python'} 22:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:14 INFO - Downloading/unpacking jsonschema==2.5.1 22:21:14 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 22:21:14 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 22:21:14 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 22:21:14 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 22:21:14 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 22:21:14 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 22:21:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21:16 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:16 INFO - warning: no files found matching '*.txt' 22:21:16 INFO - no previously-included directories found matching 'build' 22:21:16 INFO - no previously-included directories found matching 'dist' 22:21:16 INFO - no previously-included directories found matching '.git*' 22:21:16 INFO - Installing collected packages: jsonschema, functools32 22:21:16 INFO - Running setup.py install for functools32 22:21:16 INFO - warning: no files found matching '*.txt' 22:21:16 INFO - no previously-included directories found matching 'build' 22:21:16 INFO - no previously-included directories found matching 'dist' 22:21:16 INFO - no previously-included directories found matching '.git*' 22:21:16 INFO - Successfully installed jsonschema functools32 22:21:16 INFO - Cleaning up... 22:21:16 INFO - Return code: 0 22:21:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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', '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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:21: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 blobuploader==1.2.4 22:21:16 INFO - Using env: {'DISPLAY': ':0', 22:21:16 INFO - 'HOME': '/home/cltbld', 22:21:16 INFO - 'LANG': 'en_US.UTF-8', 22:21:16 INFO - 'LANGUAGE': 'en_US:en', 22:21:16 INFO - 'LOGNAME': 'cltbld', 22:21:16 INFO - 'MAIL': '/var/mail/cltbld', 22:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:16 INFO - 'MOZ_NO_REMOTE': '1', 22:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:16 INFO - 'NO_EM_RESTART': '1', 22:21:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:16 INFO - 'PWD': '/builds/slave/test', 22:21:16 INFO - 'SHELL': '/bin/bash', 22:21:16 INFO - 'SHLVL': '1', 22:21:16 INFO - 'TERM': 'linux', 22:21:16 INFO - 'TMOUT': '86400', 22:21:16 INFO - 'USER': 'cltbld', 22:21:16 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:16 INFO - '_': '/tools/buildbot/bin/python'} 22:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 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 22:21:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21:19 INFO - Downloading docopt-0.6.1.tar.gz 22:21:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:19 INFO - Running setup.py install for blobuploader 22:21:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:19 INFO - Running setup.py install for requests 22:21:20 INFO - Running setup.py install for docopt 22:21:20 INFO - Successfully installed blobuploader requests docopt 22:21:20 INFO - Cleaning up... 22:21:20 INFO - Return code: 0 22:21:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:20 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')]} 22:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:20 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')]} 22:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:20 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:20 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 22:21:20 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 22:21:20 INFO - Using env: {'DISPLAY': ':0', 22:21:20 INFO - 'HOME': '/home/cltbld', 22:21:20 INFO - 'LANG': 'en_US.UTF-8', 22:21:20 INFO - 'LANGUAGE': 'en_US:en', 22:21:20 INFO - 'LOGNAME': 'cltbld', 22:21:20 INFO - 'MAIL': '/var/mail/cltbld', 22:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:20 INFO - 'MOZ_NO_REMOTE': '1', 22:21:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:20 INFO - 'NO_EM_RESTART': '1', 22:21:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:20 INFO - 'PWD': '/builds/slave/test', 22:21:20 INFO - 'SHELL': '/bin/bash', 22:21:20 INFO - 'SHLVL': '1', 22:21:20 INFO - 'TERM': 'linux', 22:21:20 INFO - 'TMOUT': '86400', 22:21:20 INFO - 'USER': 'cltbld', 22:21:20 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:20 INFO - '_': '/tools/buildbot/bin/python'} 22:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:20 INFO - Running setup.py (path:/tmp/pip-j_DGoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:20 INFO - Running setup.py (path:/tmp/pip-oJTTiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:20 INFO - Running setup.py (path:/tmp/pip-LRJMKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:20 INFO - Running setup.py (path:/tmp/pip-hQSMIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:20 INFO - Running setup.py (path:/tmp/pip-EAmSOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:20 INFO - Running setup.py (path:/tmp/pip-DqOrtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:20 INFO - Running setup.py (path:/tmp/pip-0fnmgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:20 INFO - Running setup.py (path:/tmp/pip-lNsmgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:21 INFO - Running setup.py (path:/tmp/pip-CpAAql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:21 INFO - Running setup.py (path:/tmp/pip-dqMEe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:21 INFO - Running setup.py (path:/tmp/pip-585MZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:21 INFO - Running setup.py (path:/tmp/pip-UaG9Hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:21 INFO - Running setup.py (path:/tmp/pip-g7e133-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:21 INFO - Running setup.py (path:/tmp/pip-JUJVOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:21 INFO - Running setup.py (path:/tmp/pip-PBZTYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:21 INFO - Running setup.py (path:/tmp/pip-9ljYvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:21 INFO - Running setup.py (path:/tmp/pip-aG5TTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:21 INFO - Running setup.py install for manifestparser 22:21:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozcrash 22:21:21 INFO - Running setup.py install for mozdebug 22:21:21 INFO - Running setup.py install for mozdevice 22:21:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozfile 22:21:22 INFO - Running setup.py install for mozhttpd 22:21:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozinfo 22:21:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozInstall 22:21:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozleak 22:21:22 INFO - Running setup.py install for mozlog 22:21:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for moznetwork 22:21:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozprocess 22:21:22 INFO - Running setup.py install for mozprofile 22:21:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozrunner 22:21:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:23 INFO - Running setup.py install for mozscreenshot 22:21:23 INFO - Running setup.py install for moztest 22:21:23 INFO - Running setup.py install for mozversion 22:21:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:23 INFO - Cleaning up... 22:21:23 INFO - Return code: 0 22:21:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:23 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')]} 22:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:23 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')]} 22:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:23 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:23 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 22:21:23 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 22:21:23 INFO - Using env: {'DISPLAY': ':0', 22:21:23 INFO - 'HOME': '/home/cltbld', 22:21:23 INFO - 'LANG': 'en_US.UTF-8', 22:21:23 INFO - 'LANGUAGE': 'en_US:en', 22:21:23 INFO - 'LOGNAME': 'cltbld', 22:21:23 INFO - 'MAIL': '/var/mail/cltbld', 22:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:23 INFO - 'MOZ_NO_REMOTE': '1', 22:21:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:23 INFO - 'NO_EM_RESTART': '1', 22:21:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:23 INFO - 'PWD': '/builds/slave/test', 22:21:23 INFO - 'SHELL': '/bin/bash', 22:21:23 INFO - 'SHLVL': '1', 22:21:23 INFO - 'TERM': 'linux', 22:21:23 INFO - 'TMOUT': '86400', 22:21:23 INFO - 'USER': 'cltbld', 22:21:23 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:23 INFO - '_': '/tools/buildbot/bin/python'} 22:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:23 INFO - Running setup.py (path:/tmp/pip-tgQTo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:23 INFO - Running setup.py (path:/tmp/pip-wD0AWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:23 INFO - Running setup.py (path:/tmp/pip-VvWQQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:23 INFO - Running setup.py (path:/tmp/pip-ld5qWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:23 INFO - Running setup.py (path:/tmp/pip-cZOJ2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:24 INFO - Running setup.py (path:/tmp/pip-E4_Dtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:24 INFO - Running setup.py (path:/tmp/pip-YxxHMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:24 INFO - Running setup.py (path:/tmp/pip-uVaaxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:24 INFO - Running setup.py (path:/tmp/pip-vDGSIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:24 INFO - Running setup.py (path:/tmp/pip-nsR1pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:24 INFO - Running setup.py (path:/tmp/pip-r52n9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:24 INFO - Running setup.py (path:/tmp/pip-jrlMYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:24 INFO - Running setup.py (path:/tmp/pip-mpdydh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:24 INFO - Running setup.py (path:/tmp/pip-EucSXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:24 INFO - Running setup.py (path:/tmp/pip-tGwtzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:24 INFO - Running setup.py (path:/tmp/pip-b4xTVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:24 INFO - Running setup.py (path:/tmp/pip-WWOrKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:24 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)) 22:21:24 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)) 22:21:24 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)) 22:21:24 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)) 22:21:24 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)) 22:21:24 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)) 22:21:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21:26 INFO - Downloading blessings-1.6.tar.gz 22:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:21:26 INFO - Installing collected packages: blessings 22:21:26 INFO - Running setup.py install for blessings 22:21:26 INFO - Successfully installed blessings 22:21:26 INFO - Cleaning up... 22:21:27 INFO - Return code: 0 22:21:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:21:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:27 INFO - Reading from file tmpfile_stdout 22:21:27 INFO - Current package versions: 22:21:27 INFO - argparse == 1.2.1 22:21:27 INFO - blessings == 1.6 22:21:27 INFO - blobuploader == 1.2.4 22:21:27 INFO - docopt == 0.6.1 22:21:27 INFO - functools32 == 3.2.3-2 22:21:27 INFO - jsonschema == 2.5.1 22:21:27 INFO - manifestparser == 1.1 22:21:27 INFO - mozInstall == 1.12 22:21:27 INFO - mozcrash == 0.17 22:21:27 INFO - mozdebug == 0.1 22:21:27 INFO - mozdevice == 0.48 22:21:27 INFO - mozfile == 1.2 22:21:27 INFO - mozhttpd == 0.7 22:21:27 INFO - mozinfo == 0.9 22:21:27 INFO - mozleak == 0.1 22:21:27 INFO - mozlog == 3.2 22:21:27 INFO - moznetwork == 0.27 22:21:27 INFO - mozprocess == 0.22 22:21:27 INFO - mozprofile == 0.28 22:21:27 INFO - mozrunner == 6.11 22:21:27 INFO - mozscreenshot == 0.1 22:21:27 INFO - mozsystemmonitor == 0.3 22:21:27 INFO - moztest == 0.7 22:21:27 INFO - mozversion == 1.4 22:21:27 INFO - psutil == 3.1.1 22:21:27 INFO - requests == 1.2.3 22:21:27 INFO - wsgiref == 0.1.2 22:21:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:27 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:27 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 22:21:27 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 22:21:27 INFO - Using env: {'DISPLAY': ':0', 22:21:27 INFO - 'HOME': '/home/cltbld', 22:21:27 INFO - 'LANG': 'en_US.UTF-8', 22:21:27 INFO - 'LANGUAGE': 'en_US:en', 22:21:27 INFO - 'LOGNAME': 'cltbld', 22:21:27 INFO - 'MAIL': '/var/mail/cltbld', 22:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:27 INFO - 'MOZ_NO_REMOTE': '1', 22:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:27 INFO - 'NO_EM_RESTART': '1', 22:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:27 INFO - 'PWD': '/builds/slave/test', 22:21:27 INFO - 'SHELL': '/bin/bash', 22:21:27 INFO - 'SHLVL': '1', 22:21:27 INFO - 'TERM': 'linux', 22:21:27 INFO - 'TMOUT': '86400', 22:21:27 INFO - 'USER': 'cltbld', 22:21:27 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:27 INFO - '_': '/tools/buildbot/bin/python'} 22:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 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)) 22:21:27 INFO - Cleaning up... 22:21:27 INFO - Return code: 0 22:21:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:27 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:27 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 22:21:27 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 22:21:27 INFO - Using env: {'DISPLAY': ':0', 22:21:27 INFO - 'HOME': '/home/cltbld', 22:21:27 INFO - 'LANG': 'en_US.UTF-8', 22:21:27 INFO - 'LANGUAGE': 'en_US:en', 22:21:27 INFO - 'LOGNAME': 'cltbld', 22:21:27 INFO - 'MAIL': '/var/mail/cltbld', 22:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:27 INFO - 'MOZ_NO_REMOTE': '1', 22:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:27 INFO - 'NO_EM_RESTART': '1', 22:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:27 INFO - 'PWD': '/builds/slave/test', 22:21:27 INFO - 'SHELL': '/bin/bash', 22:21:27 INFO - 'SHLVL': '1', 22:21:27 INFO - 'TERM': 'linux', 22:21:27 INFO - 'TMOUT': '86400', 22:21:27 INFO - 'USER': 'cltbld', 22:21:27 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:27 INFO - '_': '/tools/buildbot/bin/python'} 22:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:21:27 INFO - Cleaning up... 22:21:27 INFO - Return code: 0 22:21:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:27 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 0x7f1506728f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2844350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', '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 22:21:27 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 22:21:27 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 22:21:27 INFO - Using env: {'DISPLAY': ':0', 22:21:27 INFO - 'HOME': '/home/cltbld', 22:21:27 INFO - 'LANG': 'en_US.UTF-8', 22:21:27 INFO - 'LANGUAGE': 'en_US:en', 22:21:27 INFO - 'LOGNAME': 'cltbld', 22:21:27 INFO - 'MAIL': '/var/mail/cltbld', 22:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:27 INFO - 'MOZ_NO_REMOTE': '1', 22:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:27 INFO - 'NO_EM_RESTART': '1', 22:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:27 INFO - 'PWD': '/builds/slave/test', 22:21:27 INFO - 'SHELL': '/bin/bash', 22:21:27 INFO - 'SHLVL': '1', 22:21:27 INFO - 'TERM': 'linux', 22:21:27 INFO - 'TMOUT': '86400', 22:21:27 INFO - 'USER': 'cltbld', 22:21:27 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:27 INFO - '_': '/tools/buildbot/bin/python'} 22:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:27 INFO - Downloading/unpacking flake8 22:21:27 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 22:21:27 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 22:21:27 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 22:21:27 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 22:21:27 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 22:21:27 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 22:21:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:21:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:21:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 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 22:21:29 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 22:21:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:21:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:21:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:21:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:21:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:21:30 INFO - no previously-included directories found matching 'docs/_build' 22:21:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:21:30 INFO - Running setup.py install for pep8 22:21:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:21:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:21:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:21:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:21:30 INFO - no previously-included directories found matching 'docs/_build' 22:21:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:21:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:21:30 INFO - Cleaning up... 22:21:30 INFO - Return code: 0 22:21:30 INFO - Running post-action listener: _resource_record_post_action 22:21:30 INFO - Running post-action listener: _start_resource_monitoring 22:21:30 INFO - Starting resource monitoring. 22:21:30 INFO - ##### 22:21:30 INFO - ##### Finished create-virtualenv step (success) 22:21:30 INFO - ##### 22:21:30 INFO - ##### 22:21:30 INFO - ##### Running install step. 22:21:30 INFO - ##### 22:21:30 INFO - Running pre-action listener: _resource_record_pre_action 22:21:30 INFO - Running main action method: install 22:21:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:30 INFO - Reading from file tmpfile_stdout 22:21:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:21:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:21:31 INFO - Reading from file tmpfile_stdout 22:21:31 INFO - Output received: 22:21:31 INFO - Usage: mozinstall [options] installer 22:21:31 INFO - Options: 22:21:31 INFO - -h, --help show this help message and exit 22:21:31 INFO - -d DEST, --destination=DEST 22:21:31 INFO - Directory to install application into. [default: 22:21:31 INFO - "/builds/slave/test"] 22:21:31 INFO - --app=APP Application being installed. [default: firefox] 22:21:31 INFO - mkdir: /builds/slave/test/build/application 22:21:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:21:50 INFO - Reading from file tmpfile_stdout 22:21:50 INFO - Output received: 22:21:50 INFO - /builds/slave/test/build/application/firefox/firefox 22:21:50 INFO - Running post-action listener: _resource_record_post_action 22:21:50 INFO - ##### 22:21:50 INFO - ##### Finished install step (success) 22:21:50 INFO - ##### 22:21:50 INFO - ##### 22:21:50 INFO - ##### Running run-tests step. 22:21:50 INFO - ##### 22:21:50 INFO - Running pre-action listener: _resource_record_pre_action 22:21:50 INFO - Running main action method: run_tests 22:21:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:21:50 INFO - Python 2.7.3 22:21:50 INFO - Return code: 0 22:21:50 INFO - grabbing minidump binary from tooltool 22:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d31c0>, '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 0x28d40c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d4550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:21:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:21:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:21:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:21:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:21:50 INFO - Return code: 0 22:21:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:21:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:21:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:21:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:21:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:21:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:21:51 INFO - Return code: 0 22:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:21:51 INFO - Using env: {'DISPLAY': ':0', 22:21:51 INFO - 'HOME': '/home/cltbld', 22:21:51 INFO - 'LANG': 'en_US.UTF-8', 22:21:51 INFO - 'LANGUAGE': 'en_US:en', 22:21:51 INFO - 'LOGNAME': 'cltbld', 22:21:51 INFO - 'MAIL': '/var/mail/cltbld', 22:21:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:21:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:51 INFO - 'MOZ_NO_REMOTE': '1', 22:21:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:51 INFO - 'NO_EM_RESTART': '1', 22:21:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:51 INFO - 'PWD': '/builds/slave/test', 22:21:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:21:51 INFO - 'SHELL': '/bin/bash', 22:21:51 INFO - 'SHLVL': '1', 22:21:51 INFO - 'TERM': 'linux', 22:21:51 INFO - 'TMOUT': '86400', 22:21:51 INFO - 'USER': 'cltbld', 22:21:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679', 22:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:51 INFO - '_': '/tools/buildbot/bin/python'} 22:21:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:21:51 INFO - mozversion application_buildid: 20160604193055 22:21:51 INFO - mozversion application_changeset: 4f5df0a09686af630e023c17e965977d9aa1c38a 22:21:51 INFO - mozversion application_display_name: Nightly 22:21:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:21:51 INFO - mozversion application_name: Firefox 22:21:51 INFO - mozversion application_remotingname: firefox 22:21:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:21:51 INFO - mozversion application_vendor: Mozilla 22:21:51 INFO - mozversion application_version: 49.0a1 22:21:51 INFO - mozversion platform_buildid: 20160604193055 22:21:51 INFO - mozversion platform_changeset: 4f5df0a09686af630e023c17e965977d9aa1c38a 22:21:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:21:51 INFO - mozversion platform_version: 49.0a1 22:21:51 INFO - using testdate: 1465104111 22:21:51 INFO - actual date: 1465104111 22:21:51 INFO - starting webserver on 'localhost:50126' 22:21:51 INFO - SUITE-START | Running 2 tests 22:21:51 INFO - TEST-START | dromaeo_css 22:21:51 INFO - operating with platform_type : linux_ 22:21:51 INFO - Initialising browser for dromaeo_css test... 22:21:51 INFO - TEST-INFO | started process 4462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL9zBKv/profile http://localhost:50126/getInfo.html) 22:21:57 INFO - PROCESS | 4462 | __metrics Screen width/height:1600/1200 22:21:57 INFO - PROCESS | 4462 | colorDepth:24 22:21:57 INFO - PROCESS | 4462 | Browser inner width/height: 1024/697 22:21:57 INFO - PROCESS | 4462 | __metrics 22:21:59 ERROR - PROCESS | 4462 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:21:59 INFO - PROCESS | 4462 | console.error: PushService: 22:21:59 INFO - PROCESS | 4462 | stateChangeProcessEnqueue: Error transitioning state 22:21:59 INFO - PROCESS | 4462 | UnknownError 22:22:00 INFO - TEST-INFO | 4462: exit 0 22:22:00 INFO - Browser initialized. 22:22:00 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:00 INFO - TEST-INFO | started process 4546 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL9zBKv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:11 INFO - PROCESS | 4546 | RSS: Main: 156700672 22:22:11 INFO - PROCESS | 4546 | 22:23:03 INFO - PROCESS | 4546 | 1465104183110 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] 22:25:11 INFO - PROCESS | 4546 | Cycle 1(1): loaded http://localhost:50126/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50126/tests/dromaeo/cssquery-ext.html) 22:25:12 INFO - PROCESS | 4546 | RSS: Main: 263901184 22:25:12 INFO - PROCESS | 4546 | 22:28:14 INFO - PROCESS | 4546 | Cycle 1(1): loaded http://localhost:50126/tests/dromaeo/cssquery-ext.html (next: http://localhost:50126/tests/dromaeo/cssquery-jquery.html) 22:28:15 INFO - PROCESS | 4546 | RSS: Main: 240914432 22:28:15 INFO - PROCESS | 4546 | 22:31:17 INFO - PROCESS | 4546 | Cycle 1(1): loaded http://localhost:50126/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50126/tests/dromaeo/cssquery-mootools.html) 22:31:18 INFO - PROCESS | 4546 | RSS: Main: 241475584 22:31:18 INFO - PROCESS | 4546 | 22:34:20 INFO - PROCESS | 4546 | Cycle 1(1): loaded http://localhost:50126/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50126/tests/dromaeo/cssquery-prototype.html) 22:34:20 INFO - PROCESS | 4546 | RSS: Main: 237297664 22:34:20 INFO - PROCESS | 4546 | 22:37:23 INFO - PROCESS | 4546 | Cycle 1(1): loaded http://localhost:50126/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50126/tests/dromaeo/cssquery-yui.html) 22:37:23 INFO - PROCESS | 4546 | RSS: Main: 219254784 22:37:23 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | Cycle 1(1): loaded http://localhost:50126/tests/dromaeo/cssquery-yui.html (next: http://localhost:50126/tests/dromaeo/cssquery-dojo.html) 22:40:27 INFO - PROCESS | 4546 | RSS: Main: 271527936 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | __start_tp_report 22:40:27 INFO - PROCESS | 4546 | _x_x_mozilla_page_load 22:40:27 INFO - PROCESS | 4546 | _x_x_mozilla_page_load_details 22:40:27 INFO - PROCESS | 4546 | |i|pagename|runs| 22:40:27 INFO - PROCESS | 4546 | |0;dojo.html;3108.052044126541;3121.360121175158;3124.3282694220743;3162.5730526378934;3172.3020935394206;5265.76304066317;5340.706261155635;5357.062514060033;5438.700871452069;5487.149491828768;5437.075697131488;5447.373552041517;5488.890222195554;5493.615446918708;5548.4174161713045;4895.828645997391;4950.183219768734;4984.551390374865;5002;5078.923816142742;4984.77568509418;5008.774605142778;5029.270755740416;5045.318881950945;5054.469280725526;4759.690620109682;4886;4887.291342755316;4906.558409743122;4948.406191257054;4849.175640141185;4863.19757240055;4952.678075925053;4960.851174464736;5007.4992500749995;4728.148933192058;4902.268682608774;4919.163742163841;4939.8024079036795;4980.900381992376;5275.683458992473;5351.143816989302;5366.70483123428;5406.297181366397;5436.646617969819;5826.708664566755;5829.8251052468495;5846.9707651461895;5852.034414321632;5860.619059761145;5625.8593535161535;5789.594728369016;5799.623024503394;5914.911276330816;5961.284645842483;5473.917891231635;5476.890462190774;5492.176173573956;5495.120780675073;5542.224088627595;6046.338374431997;6081.452669259788;6091.360407157274;6094.634321940609;6097.7865774697675;5448.673079615236;5728.226689396962;5784.016717158093;5791.7683292667825;5821.38875418075;5917.467427931507;5964.821055368346;5972.014617588136;5972.731227094791;5977.581569290108;5878.676672783038;5880.353161152269;5884.764609415661;5885.176075349455;5891.793787217427;5522;5774.393688662714;5807.419258074159;5849.034909240013;5896.970515147483;4457.665675074382;4460.1971645104195;4461.888452788707;4465.486469056035;4506.031203291308;3827.693784497289;3935.960640393617;3979.045029192973;3996.6403023728003;4021.1354558770004;5349.090654588729;5404.162354835007;5417.6478528895495;5419.214213938957;5439.3200849893765;5869.735861886225;5876.676782776988;5878.47093761555;5880.0591905295805;5923;5254.448282930313;5406.729663516887;5441.727913604304;5448.564114870801;5452.454754524595;5037.672551284155;5065.949340506621;5069.493050694901;5070.898582028339;5114.258432527263;6101.420365065312;6108.633481991094;6111.419415155553;6112.7554897804475;6114.2662880453745;5608.915866262091;5704.030314846403;5766.942330576809;5911.852203694942;5941.257342832146;5954.523638109027;5958.821235362946;5963.2844058713235;5964.194833697396;6006.309274433322;5676.148577713376;5706.771729130955;5714.542836573148;5717;5727.8568035799435;5647.576431767716;5797.594168408171;5826.213461182686;5830.737616807169;5850.444207800252;5730.341010783646;5738.569607279387;5738.8278351649515;5744.942550574441;5746.9712651436475;5648.887022259614;5687.658740475585;5699.772009119754;5699.971500142473;5701.087825947994;5445;5545.972270138624;5585.720713964367;5615.18579805934;5635.239242702182;5734.311882574118;5748.48263656273;5781.190633311424;5784.161296612052;5791.276090488689;5382.434844341445;5558.833235002957;5604.8038318658655;5612;5642.85892852682 22:40:27 INFO - PROCESS | 4546 | |1;ext.html;584.4126652714021;623.149401067543;635.1045026512617;635.3455940381407;643.1606753187089;32182.839085804568;32763.67236327639;34919.65080349199;34944.30111397771;34954.65045349546;44506;45575.54424455763;45585.316220256726;49298.75350623242;49348.75325623376;8772.42979206351;8896.59965301563;8901.376903616749;8903.198712115909;8933.196012358887;8695.69565065223;8806.64773409063;8814.603342849556;8828.028916819145;8829.60266787996;8799.560021998906;8845.248153906925;8853.070427605106;8862.822743545124;8865.822683546325;8768.386212965095;8824.294056475466;8829.602667879928;8831.425957312755;8832;8798.912010879872;8893.955530222373;8911.55442227886;8919.821603567892;8921.241694455979;40734.79632601818;40846.38730419046;41005.1798964022;41007.589924100976;41047.7947610263;3824.2733880562882;3853.3064048471265;4133.81397837098;4285.121550082225;4294;33252.966716338786;33535;34008.82995585031;34152.48771268433;34593.6540634593;4358.346248062784;4407.853703565115;4444.088961762864;4458.219811532968;4492.16894874446;353092;353148;353208;353829;355353.2232338848;59823;60413.37519748829;60711;60735;60864;63143.36856631375;63729.681351592946;63920.36079639144;63926.36073639204;63926.36073639297;15820.288087036111;15923.12422816733;15932.123733194785;15939.760903586224;15950.202489875459;13549.458021679022;13594.932025340007;13635.250061246528;13638.727225455435;13639.727205456034;17203.913980430018;17428.651426971388;17469.21388537519;17469.7379539307;17551.385701500643;5773.690523790433;5871.178035075093;6000.909986350208;6005.207578475943;6017.638941663514;993.9105480506645;1096.4188979840696;1104.1885751701468;1121.3664279681955;1146.896779289868;32843.50734738981;32916.83541582277;33081.83459082689;33092.33815323728;33759.31203439818;1099.5217080569978;1121.6803211084964;1190.7021346732104;1209.087139209891;1233.5189276182296;17141.228644711016;17198.65602687939;17226;17321.133943302786;17333.306667733403;510188;510702;513053;514188;515347;59693;59755.402445974985;59966.700166498886;60007;60030;1270.1934271737514;1396.4484028808563;1401.3063533550753;1430.0990376063014;1432.5630682641895;32960.83519582387;33691.32617347688;34491.13772155716;35043.12392190113;36307.45538816973;57992.71003644955;58676;58887.70556147192;58944.41055589561;58982;503738;508047;511423;513229;513687;27717.30706732348;28406.43187136287;28414.715852841207;28546.000889968753;29829.403411932075;49195.754021229666;49329.75335123301;49465.03209807423;50727;50998.23502647538;41398.58601414068;42278;42758.78620607001;42913.78543107265;43729.56270437255;41457.1708565833;41703.37444938389;41730.374044389966;42451.15097697967;42713.145737084466;41541.37687934744;41567;42260;42418.78790606027;42747;35906;37703.00761201083;37915;38302.808485957394;38479;28212.435751284447;28341.874359422713;28470.152578789435;28585.85707071451;28873.711262887104 22:40:27 INFO - PROCESS | 4546 | |2;jquery.html;389.26040523006264;390.77335145615535;405.9553449120596;411.69123157631776;422.1704350950382;18062.09689515524;18522.166502507385;19519.902400487994;19548.511287217825;19638.410847674593;18290.542736431595;18350.816491835078;18414.079296035212;18626.25494980199;18961.14674839632;5502.312210973629;5642.238297829782;5740.56945729072;5746.741396637161;5837.854053648651;5765.1352297155345;5765.481106700396;5771.278590176228;5799.681017544033;5808;5593.04918163911;5966.850828729273;6220.813375598716;6289.779857704982;6385.393387628168;5704.458076482769;6146.416090471398;6285.465735412496;6308.526860485459;6322.1465102211305;5514.641548299388;5612.214289999403;5641.323041235078;5644.153376993443;5654.123610840326;13423.597292081253;14579.635509112251;22157.66763498549;22270.554588908293;22293.66559501609;2139.8181154601875;2220.200727738029;2402.147237730598;2649.3509090273005;2879;9373.671921482784;9400.952995235048;9464.148226659634;9760.219182465386;9976.551055202533;1863.3943968210385;1910.646530391868;1940.185122248659;1959.2358979997812;1992.8903910285073;81969;82340.17659823385;82646.58676706639;82911.585442073;82932;10747;10819.675409737563;10948.397838118979;11065.668029959113;11257.71855703614;15329.770053449207;15330.846691532943;15382.769258461132;15383.077015378889;15397.07617542951;7860.764177074697;8219.671213151407;8233.876491852627;8340.874886876702;8412.873806892901;7866.9606651967515;7884.684612615546;7922.841543169219;8080.959595201986;8100.756977290573;4421.602055814992;4760.904991851824;4777.665563410597;4808.831690890802;4813.7352445615825;1944.4069558784424;1951.1122439290089;2002.8698134621202;2017.3141132015185;2017.8587498874938;856.3834039491556;925.1812146250617;1075.8493810866485;1081.551156270139;1100.8238681810872;17362.826371736122;17933.282668693108;18269.54326141857;18845.623087538446;18896.527586810163;991.8908920018793;997.7904640025675;1009.6365308488938;1014.3102690170607;1039.7504598896362;8666.263367613823;10447.634332798316;10452.843207351896;10528.947355263328;10558.947205264078;504155;505980;518393;522432;535990;89019;90449.54775226214;91271.54364228269;92474.07525924654;92597;1003.1623594298842;1014.7260239735231;1043.5617040842865;1061.6974162363845;1062.2829590026702;16342.509724708278;16571.368646035764;16649.833501664827;16711.331546738482;16979.151042447582;29570;35478.113047174025;39035.41446878242;40160.59839401569;40649.79675101605;55227.72386138044;55844.16233756416;55864.162037570284;56397;57635.7118214423;7480.775576732707;7714.498557593738;8051.8792218115605;8521.786955326166;8631.95684021601;12855.550055748377;13016.869831301567;13293.401796919356;13313.267770272729;13421.798673019717;11331.376774277494;11332.433378331216;11576;11992.280463172237;12100.636980890587;11335.319880807177;11500.94249528747;11531.3081215124;11646.184767066225;11763.117766167743;11752.941235293769;11864.22882512634;12094.45574949147;12192.207506512048;12324.260544367367;7345.632718364167;7640.503367281109;7860;7893.210678932061;7950.363970882431;10296.536655850356;11940.641780746591;12050.819237711617;12184.329861857683;12194.939025304817 22:40:27 INFO - PROCESS | 4546 | |3;mootools.html;1362.8909687225023;1481.2963842174966;1533.1261181126756;1683.7306031035037;1824.7901491328494;8281;8591.441556298836;8601.870971935417;8624;8818;8229.958850205756;8395.91604083959;8664.480131192133;8694.782630434242;8714;2039.9286024989128;2274.749777524471;2318.385627808632;2339.532093581282;2370.1349007612184;2379.500304935957;2394.1500767227644;2395.688560487134;2396.281115665302;2399.9280021599293;2317.0963324303452;2336.6495025746103;2348.0725113580124;2353.4587044979667;2353.752855950126;1940.4275738657059;2164.4372463159534;2239.3393948785147;2246.5057687308768;2260.7965283124518;2309.8845057747044;2324.9883750581307;2328.7089113860766;2347.319277409549;2349.6475528670667;8751.606177722018;8781.560921953876;8798.912010879872;8802.559872006437;8807.515586642732;5200.765965531522;5319.6808191508635;5333.599980001515;5339.065663508871;5360.383555891083;3244.886428974998;3326.5176549400203;3376.5610470638658;3390.3897298486263;3503.036664917143;1133.8922802333766;1153.988460115405;1154.1632316570503;1189.2032338333252;1194.713268815478;12419.75160496794;12617.864392204838;12624.747505049847;12665.430055647517;12674.556390526288;4474.351219073216;4589.380433641416;4657.441107067174;4675.719456832601;4677.836275730401;10658.360498370123;10687.83968240477;10692.62575809843;10694.144468442506;10697.465126743631;5258.474152584788;5301.390340110936;5305.098133317345;5308.124159513714;5308.681479111266;4166.500019997559;4751.59611433032;4860.975695121501;4872.878178045577;4945.307656928033;4048.6712510651746;4069;4088.4684990950987;4144.274751918402;4157.064660451374;1264.3235868810143;1265.4938024790158;1266.1200465676266;1288.6842723532795;1293.5731208701106;1032.9690109296732;1034.5603118674683;1039.8076355874186;1045.639254457211;1046.9005444482762;6036.245469316335;6099.939000610026;6164.167837341902;6215.81352559424;6319.146915166486;465.58694362609316;473.2073776424475;479.69779039205076;479.93280940668336;483.0556262506791;4148.979255103705;4232.174725928476;4329.437173167531;4343.109662519176;4395.758233297135;10651.254412191227;11164.385958772345;11175.664730058112;11216.495257713423;11246.775064498665;10357.792844143074;10694.786104277871;10750.516226769814;11099.278546894426;11134.721631959266;8359.456635318686;8431.536265505334;8491.320694344438;8491.448055876348;8549.743507694655;6075.665838378932;6298.244210694746;6338.683065846782;6347.523935704933;6376.489880809617;10252.846207306748;10397.740056498647;10507.211959103252;10515.421651808918;10517.05346518817;9856.162226210852;10252.948735256276;10278.743031424274;10507.89492105069;10538.315009524356;7036.753713619999;7511.887321690069;7669.808254793675;7845.3723702104835;8039.959800200962;8535.615897284513;8546.401751877558;8604.956975215084;8613.26787223093;8626.18051285127;7297.525660832029;7598.0502437195355;7644.770656880302;7736.187700291388;7801;7405.925940740523;7486.213947535431;7542.924570754222;7558.055243094614;7713.498622589514;6725.39471447572;6773.322667733188;6805.3875151236625;6807.761728339485;6813.080234168324;4900.117978763857;4969;5026.145555255543;5189.091908916;5194.35070616173;4926.285688575065;5075.87310317245;5206.479352064764;5276.5251127398715;5282.524572788468 22:40:27 INFO - PROCESS | 4546 | |4;prototype.html;332.476349749145;333.87312821127966;338.9898303050906;341.85642030347253;342.9916046822342;5266.7629956651945;5291.68249905006;5298.708571028592;5329.786808527664;5334.199870019499;5207.270982062505;5217.57382816991;5222.738863056851;5226.18994055921;5230.947690523094;3949.743266687656;3970.5235371755284;4075.347944328893;4106.568810274923;4116.156187981456;4032.7580345179235;4057.1885622875398;4058.715889887709;4070.287699652564;4081.7755023473696;3903.765774053566;4024.7585144891254;4073.450084238634;4078.7552746835136;4080.6735461163034;3786.1859700164596;4010.418489319047;4039.9192016159805;4055.2092341993325;4062.6952978526433;3610.8194590270646;3627.6372362763777;3642.6357364263627;3643.872464463731;3648.908777280563;4942.258661200813;5015.924761128586;5046.7476626168545;5048.697078175284;5073.365829271341;4495.550444955511;4611.44662640482;4627.838025669085;4675.485696573374;4718.433787945434;5341.679499230058;5347.732613369316;5373.919391209135;5394.217838413448;5407.215953686733;3959.821808018646;3965.306071437487;3965.7025723070883;3970.3250447424007;3986.4219688145345;5382.30030096085;5610.204734484925;5616.887662246773;5633.830985070414;5682.431756824326;3913.8434462621744;3950.170464202492;3960.980195099006;3964.861229856941;3982.3628219484744;4007.3970411834916;4007.73949693269;4023.6982226333143;4057.594240575919;4064.435192450928;4465.12929978651;4621.792019359137;4637.188492013884;4651.883702907455;4670.696404733681;5458.536024437967;5465.289512363367;5465.977588292358;5471.699056551848;5483.972580137153;1375.628580283338;1379.406855052337;1380.42712274407;1386.7573174694387;1394.5537428022935;852.9849479119828;893.9508327042075;895.8790563273875;909.3589019741066;920.2316066084894;5104.923426148611;5154.201098829687;5259.973700131475;5272.446393128666;5286.577073834084;5592.133219351007;5608.46719561641;5644.858878527987;5652.7173641318595;5652.830415087554;4925.827596034122;5145.202493613496;5228.398734145546;5266.183741519994;5290.672104274107;2943.484890144216;2956.2757124504205;2977.196157037631;2980.344324248662;3002.6697063323018;5773.682447465346;5823.893460242541;5825.621334613236;5837.503812175928;5844.094165404369;5575.275214222125;5687.43125687428;5719.7712091516705;5730.914036289459;5755.8561035974435;5766.221560089418;5782.669985903266;5786.189933409326;5798.5651076168615;5816.680082595498;5319.749858783105;5567.721613919369;5613;5619.8876022480135;5633.492985631313;5496.148097044965;5506.91739623898;5543.974364742536;5561.388247292875;5565.332160140809;5732.34078081026;5776.631581051749;5780.479756821906;5791.247137872049;5797.768089276382;3595.244998550229;3675.7794532328144;3688.5573731152435;3705.999380167286;3711.758735682172;4980.708873918038;4983.775730092227;4994.2520117959875;5009.223570346602;5049.37432535087;4831.468538460696;4910.2880082388565;4947.356843610308;4952.439981405845;4958.107540642719;4612.953870461387;4830.686005409637;4885.413750349981;4914.262860570943;4950.925736113889;4767.330295034792;4787.329773831736;4798.480797581297;4868.586170175433;5138.229265610188;5343.686914113275;5452.918206226989;5484.287042684426;5514.09017512106;5522.282103326542;5288.68267903927;5311.973440132774;5313.388960269618;5332.920006199833;5373.4357892422295 22:40:27 INFO - PROCESS | 4546 | |5;yui.html;692.390696187355;718.3068339052813;757.0423414380809;809.9352051835851;823.7611092783093;14443.78334324986;15083.622909427257;15381.23093845306;15405.922970385147;15409.537713868602;13806.309684515783;13920.651983700413;13934.581962541115;13936;13938.581842544714;671.8723442545901;790.0637744273026;802.5024484819405;813.63386476086;816.4121832280748;805.1948051948052;809.9028116626013;811.4157806379427;812.2608426332039;812.5206128384252;861.104451370577;861.9741407757746;862.6463150108457;862.8533149364591;862.9870551941726;710.133636962907;832.7335252719132;851.4805968359295;855.055164043732;856.4390324337569;626.1765778001912;626.6960524145786;627.0468887291337;627.5983370642792;627.8399008252866;13800.585982420544;13818.723625527533;13826.930865345608;13828.516001939985;13832.515861944885;12263.099219620884;12412.006476522605;12477.50089996399;12644.051696122735;12852.036097292741;10312.845307320396;10639.521221545048;10898.073663738593;11251.662450126509;11378.487968041458;4348.673849461272;4375.606195442394;4377.190219809346;4468.329750537413;4591.127685739699;77888.61055694742;78291;78682;78711.21288787153;79385.20614793836;10622.628208012682;10777.515011824487;10852.077573406192;10868.239223254297;10945.4527273636;18737.812621873607;18758.906205469157;18767.530811729815;18767.718484222747;18793;8043.316318112909;8056.264084594286;8090.150534193943;8117.512949223065;8318.8336233275;4648.48866624671;4671.229247174246;4686.8125274988615;4730.455997560324;4777.307290442866;3306.669333066674;3393.5927688677025;3394.8472318745717;3403.1151900506047;3404.029851492313;597.3608239184118;606.4087514673247;622.3030206169119;625.0686477149106;691.0705101638289;622.1787240842045;638.1990601794718;646.7348387161242;647.9676016199171;699.5977313044917;9219.90780092204;9406;9430.62277508892;9694.079062489052;9759.658411955548;587.5358466811172;669.9464042876558;693.8508220732567;705.1855107351017;712.3624356201171;4263.614076788811;4394.670399720034;4486.327050942385;4521.073179998092;4543.159515489645;76070;77475;77810.6109469449;93753;119494;101425;110738.44630776794;111308;111497.44251278853;111915.80841915743;95708.52145739227;96286.0371396291;97712.51143744378;98588.50705746569;100245.99754002658;8673.132686731276;8702.216800487986;8882.578787393986;8905;8921.068652009379;43718;44468.55531444774;55005;56355;57442.7127864358;75109.24890751022;75244;75391;75870.24129758852;76551;12641;12756.61730148097;12788;13139.343032848128;13397.171873373894;12798.680032998876;13004.21974681522;13642.052565706867;13680.65798355049;14087.859121408655;12482.625521234377;12724.491020359452;12737.681557960754;12908.290044047291;12974.870251297743;11915.52337906474;12119.333436660716;12262.877371226174;12548.309842958724;12551.874481255436;11958.402079895797;12020.63938081859;12112.939435302767;12458.937705311415;12737.108402411743;9756.317057805885;9764.170045546209;9767.902320976984;9838.212942964688;10255.487225638837;9081.137291957253;9452.480113593818;9458.43249405038;10038.749031273985;10309.432981186106 22:40:27 INFO - PROCESS | 4546 | __end_tp_report 22:40:27 INFO - PROCESS | 4546 | __start_cc_report 22:40:27 INFO - PROCESS | 4546 | _x_x_mozilla_cycle_collect,959 22:40:27 INFO - PROCESS | 4546 | __end_cc_report 22:40:27 INFO - PROCESS | 4546 | __startTimestamp1465105227389__endTimestamp 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | ------- Summary: start ------- 22:40:27 INFO - PROCESS | 4546 | Number of tests: 6 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | [#0] dojo.html Cycles:165 Average:5409.48 Median:5604.80 stddev:626.38 (11.2%) stddev-sans-first:601.72 22:40:27 INFO - PROCESS | 4546 | Values: 3108.1 3121.4 3124.3 3162.6 3172.3 5265.8 5340.7 5357.1 5438.7 5487.1 5437.1 5447.4 5488.9 5493.6 5548.4 4895.8 4950.2 4984.6 5002.0 5078.9 4984.8 5008.8 5029.3 5045.3 5054.5 4759.7 4886.0 4887.3 4906.6 4948.4 4849.2 4863.2 4952.7 4960.9 5007.5 4728.1 4902.3 4919.2 4939.8 4980.9 5275.7 5351.1 5366.7 5406.3 5436.6 5826.7 5829.8 5847.0 5852.0 5860.6 5625.9 5789.6 5799.6 5914.9 5961.3 5473.9 5476.9 5492.2 5495.1 5542.2 6046.3 6081.5 6091.4 6094.6 6097.8 5448.7 5728.2 5784.0 5791.8 5821.4 5917.5 5964.8 5972.0 5972.7 5977.6 5878.7 5880.4 5884.8 5885.2 5891.8 5522.0 5774.4 5807.4 5849.0 5897.0 4457.7 4460.2 4461.9 4465.5 4506.0 3827.7 3936.0 3979.0 3996.6 4021.1 5349.1 5404.2 5417.6 5419.2 5439.3 5869.7 5876.7 5878.5 5880.1 5923.0 5254.4 5406.7 5441.7 5448.6 5452.5 5037.7 5065.9 5069.5 5070.9 5114.3 6101.4 6108.6 6111.4 6112.8 6114.3 5608.9 5704.0 5766.9 5911.9 5941.3 5954.5 5958.8 5963.3 5964.2 6006.3 5676.1 5706.8 5714.5 5717.0 5727.9 5647.6 5797.6 5826.2 5830.7 5850.4 5730.3 5738.6 5738.8 5744.9 5747.0 5648.9 5687.7 5699.8 5700.0 5701.1 5445.0 5546.0 5585.7 5615.2 5635.2 5734.3 5748.5 5781.2 5784.2 5791.3 5382.4 5558.8 5604.8 5612.0 5642.9 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | [#1] ext.html Cycles:180 Average:62253.30 Median:42434.97 stddev:123296.93 (290.6%) stddev-sans-first:123555.87 22:40:27 INFO - PROCESS | 4546 | Values: 584.4 623.1 635.1 635.3 643.2 32182.8 32763.7 34919.7 34944.3 34954.7 44506.0 45575.5 45585.3 49298.8 49348.8 8772.4 8896.6 8901.4 8903.2 8933.2 8695.7 8806.6 8814.6 8828.0 8829.6 8799.6 8845.2 8853.1 8862.8 8865.8 8768.4 8824.3 8829.6 8831.4 8832.0 8798.9 8894.0 8911.6 8919.8 8921.2 40734.8 40846.4 41005.2 41007.6 41047.8 3824.3 3853.3 4133.8 4285.1 4294.0 33253.0 33535.0 34008.8 34152.5 34593.7 4358.3 4407.9 4444.1 4458.2 4492.2 353092.0 353148.0 353208.0 353829.0 355353.2 59823.0 60413.4 60711.0 60735.0 60864.0 63143.4 63729.7 63920.4 63926.4 63926.4 15820.3 15923.1 15932.1 15939.8 15950.2 13549.5 13594.9 13635.3 13638.7 13639.7 17203.9 17428.7 17469.2 17469.7 17551.4 5773.7 5871.2 6000.9 6005.2 6017.6 993.9 1096.4 1104.2 1121.4 1146.9 32843.5 32916.8 33081.8 33092.3 33759.3 1099.5 1121.7 1190.7 1209.1 1233.5 17141.2 17198.7 17226.0 17321.1 17333.3 510188.0 510702.0 513053.0 514188.0 515347.0 59693.0 59755.4 59966.7 60007.0 60030.0 1270.2 1396.4 1401.3 1430.1 1432.6 32960.8 33691.3 34491.1 35043.1 36307.5 57992.7 58676.0 58887.7 58944.4 58982.0 503738.0 508047.0 511423.0 513229.0 513687.0 27717.3 28406.4 28414.7 28546.0 29829.4 49195.8 49329.8 49465.0 50727.0 50998.2 41398.6 42278.0 42758.8 42913.8 43729.6 41457.2 41703.4 41730.4 42451.2 42713.1 41541.4 41567.0 42260.0 42418.8 42747.0 35906.0 37703.0 37915.0 38302.8 38479.0 28212.4 28341.9 28470.2 28585.9 28873.7 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | [#2] jquery.html Cycles:180 Average:29384.99 Median:32524.06 stddev:85196.86 (262.0%) stddev-sans-first:85408.04 22:40:27 INFO - PROCESS | 4546 | Values: 389.3 390.8 406.0 411.7 422.2 18062.1 18522.2 19519.9 19548.5 19638.4 18290.5 18350.8 18414.1 18626.3 18961.1 5502.3 5642.2 5740.6 5746.7 5837.9 5765.1 5765.5 5771.3 5799.7 5808.0 5593.0 5966.9 6220.8 6289.8 6385.4 5704.5 6146.4 6285.5 6308.5 6322.1 5514.6 5612.2 5641.3 5644.2 5654.1 13423.6 14579.6 22157.7 22270.6 22293.7 2139.8 2220.2 2402.1 2649.4 2879.0 9373.7 9401.0 9464.1 9760.2 9976.6 1863.4 1910.6 1940.2 1959.2 1992.9 81969.0 82340.2 82646.6 82911.6 82932.0 10747.0 10819.7 10948.4 11065.7 11257.7 15329.8 15330.8 15382.8 15383.1 15397.1 7860.8 8219.7 8233.9 8340.9 8412.9 7867.0 7884.7 7922.8 8081.0 8100.8 4421.6 4760.9 4777.7 4808.8 4813.7 1944.4 1951.1 2002.9 2017.3 2017.9 856.4 925.2 1075.8 1081.6 1100.8 17362.8 17933.3 18269.5 18845.6 18896.5 991.9 997.8 1009.6 1014.3 1039.8 8666.3 10447.6 10452.8 10528.9 10558.9 504155.0 505980.0 518393.0 522432.0 535990.0 89019.0 90449.5 91271.5 92474.1 92597.0 1003.2 1014.7 1043.6 1061.7 1062.3 16342.5 16571.4 16649.8 16711.3 16979.2 29570.0 35478.1 39035.4 40160.6 40649.8 55227.7 55844.2 55864.2 56397.0 57635.7 7480.8 7714.5 8051.9 8521.8 8632.0 12855.6 13016.9 13293.4 13313.3 13421.8 11331.4 11332.4 11576.0 11992.3 12100.6 11335.3 11500.9 11531.3 11646.2 11763.1 11752.9 11864.2 12094.5 12192.2 12324.3 7345.6 7640.5 7860.0 7893.2 7950.4 10296.5 11940.6 12050.8 12184.3 12194.9 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | [#3] mootools.html Cycles:180 Average:5828.85 Median:4531.87 stddev:3353.57 (74.0%) stddev-sans-first:3346.19 22:40:27 INFO - PROCESS | 4546 | Values: 1362.9 1481.3 1533.1 1683.7 1824.8 8281.0 8591.4 8601.9 8624.0 8818.0 8230.0 8395.9 8664.5 8694.8 8714.0 2039.9 2274.7 2318.4 2339.5 2370.1 2379.5 2394.2 2395.7 2396.3 2399.9 2317.1 2336.6 2348.1 2353.5 2353.8 1940.4 2164.4 2239.3 2246.5 2260.8 2309.9 2325.0 2328.7 2347.3 2349.6 8751.6 8781.6 8798.9 8802.6 8807.5 5200.8 5319.7 5333.6 5339.1 5360.4 3244.9 3326.5 3376.6 3390.4 3503.0 1133.9 1154.0 1154.2 1189.2 1194.7 12419.8 12617.9 12624.7 12665.4 12674.6 4474.4 4589.4 4657.4 4675.7 4677.8 10658.4 10687.8 10692.6 10694.1 10697.5 5258.5 5301.4 5305.1 5308.1 5308.7 4166.5 4751.6 4861.0 4872.9 4945.3 4048.7 4069.0 4088.5 4144.3 4157.1 1264.3 1265.5 1266.1 1288.7 1293.6 1033.0 1034.6 1039.8 1045.6 1046.9 6036.2 6099.9 6164.2 6215.8 6319.1 465.6 473.2 479.7 479.9 483.1 4149.0 4232.2 4329.4 4343.1 4395.8 10651.3 11164.4 11175.7 11216.5 11246.8 10357.8 10694.8 10750.5 11099.3 11134.7 8359.5 8431.5 8491.3 8491.4 8549.7 6075.7 6298.2 6338.7 6347.5 6376.5 10252.8 10397.7 10507.2 10515.4 10517.1 9856.2 10252.9 10278.7 10507.9 10538.3 7036.8 7511.9 7669.8 7845.4 8040.0 8535.6 8546.4 8605.0 8613.3 8626.2 7297.5 7598.1 7644.8 7736.2 7801.0 7405.9 7486.2 7542.9 7558.1 7713.5 6725.4 6773.3 6805.4 6807.8 6813.1 4900.1 4969.0 5026.1 5189.1 5194.4 4926.3 5075.9 5206.5 5276.5 5282.5 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | [#4] prototype.html Cycles:180 Average:4522.19 Median:5031.34 stddev:1327.71 (26.4%) stddev-sans-first:1293.66 22:40:27 INFO - PROCESS | 4546 | Values: 332.5 333.9 339.0 341.9 343.0 5266.8 5291.7 5298.7 5329.8 5334.2 5207.3 5217.6 5222.7 5226.2 5230.9 3949.7 3970.5 4075.3 4106.6 4116.2 4032.8 4057.2 4058.7 4070.3 4081.8 3903.8 4024.8 4073.5 4078.8 4080.7 3786.2 4010.4 4039.9 4055.2 4062.7 3610.8 3627.6 3642.6 3643.9 3648.9 4942.3 5015.9 5046.7 5048.7 5073.4 4495.6 4611.4 4627.8 4675.5 4718.4 5341.7 5347.7 5373.9 5394.2 5407.2 3959.8 3965.3 3965.7 3970.3 3986.4 5382.3 5610.2 5616.9 5633.8 5682.4 3913.8 3950.2 3961.0 3964.9 3982.4 4007.4 4007.7 4023.7 4057.6 4064.4 4465.1 4621.8 4637.2 4651.9 4670.7 5458.5 5465.3 5466.0 5471.7 5484.0 1375.6 1379.4 1380.4 1386.8 1394.6 853.0 894.0 895.9 909.4 920.2 5104.9 5154.2 5260.0 5272.4 5286.6 5592.1 5608.5 5644.9 5652.7 5652.8 4925.8 5145.2 5228.4 5266.2 5290.7 2943.5 2956.3 2977.2 2980.3 3002.7 5773.7 5823.9 5825.6 5837.5 5844.1 5575.3 5687.4 5719.8 5730.9 5755.9 5766.2 5782.7 5786.2 5798.6 5816.7 5319.7 5567.7 5613.0 5619.9 5633.5 5496.1 5506.9 5544.0 5561.4 5565.3 5732.3 5776.6 5780.5 5791.2 5797.8 3595.2 3675.8 3688.6 3706.0 3711.8 4980.7 4983.8 4994.3 5009.2 5049.4 4831.5 4910.3 4947.4 4952.4 4958.1 4613.0 4830.7 4885.4 4914.3 4950.9 4767.3 4787.3 4798.5 4868.6 5138.2 5343.7 5452.9 5484.3 5514.1 5522.3 5288.7 5312.0 5313.4 5332.9 5373.4 22:40:27 INFO - PROCESS | 4546 | 22:40:27 INFO - PROCESS | 4546 | [#5] yui.html Cycles:180 Average:20298.74 Median:4753.88 stddev:29957.24 (630.2%) stddev-sans-first:30005.10 22:40:27 INFO - PROCESS | 4546 | Values: 692.4 718.3 757.0 809.9 823.8 14443.8 15083.6 15381.2 15405.9 15409.5 13806.3 13920.7 13934.6 13936.0 13938.6 671.9 790.1 802.5 813.6 816.4 805.2 809.9 811.4 812.3 812.5 861.1 862.0 862.6 862.9 863.0 710.1 832.7 851.5 855.1 856.4 626.2 626.7 627.0 627.6 627.8 13800.6 13818.7 13826.9 13828.5 13832.5 12263.1 12412.0 12477.5 12644.1 12852.0 10312.8 10639.5 10898.1 11251.7 11378.5 4348.7 4375.6 4377.2 4468.3 4591.1 77888.6 78291.0 78682.0 78711.2 79385.2 10622.6 10777.5 10852.1 10868.2 10945.5 18737.8 18758.9 18767.5 18767.7 18793.0 8043.3 8056.3 8090.2 8117.5 8318.8 4648.5 4671.2 4686.8 4730.5 4777.3 3306.7 3393.6 3394.8 3403.1 3404.0 597.4 606.4 622.3 625.1 691.1 622.2 638.2 646.7 648.0 699.6 9219.9 9406.0 9430.6 9694.1 9759.7 587.5 669.9 693.9 705.2 712.4 4263.6 4394.7 4486.3 4521.1 4543.2 76070.0 77475.0 77810.6 93753.0 119494.0 101425.0 110738.4 111308.0 111497.4 111915.8 95708.5 96286.0 97712.5 98588.5 100246.0 8673.1 8702.2 8882.6 8905.0 8921.1 43718.0 44468.6 55005.0 56355.0 57442.7 75109.2 75244.0 75391.0 75870.2 76551.0 12641.0 12756.6 12788.0 13139.3 13397.2 12798.7 13004.2 13642.1 13680.7 14087.9 12482.6 12724.5 12737.7 12908.3 12974.9 11915.5 12119.3 12262.9 12548.3 12551.9 11958.4 12020.6 12112.9 12458.9 12737.1 9756.3 9764.2 9767.9 9838.2 10255.5 9081.1 9452.5 9458.4 10038.7 10309.4 22:40:27 INFO - PROCESS | 4546 | -------- Summary: end -------- 22:40:27 INFO - PROCESS | 4546 | 22:40:28 INFO - TEST-INFO | 4546: exit 0 22:40:28 INFO - TEST-OK | dromaeo_css | took 1116967ms 22:40:28 INFO - TEST-START | kraken 22:40:28 INFO - operating with platform_type : linux_ 22:40:28 INFO - Initialising browser for kraken test... 22:40:28 INFO - TEST-INFO | started process 4730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWmEzB6/profile http://localhost:50126/getInfo.html) 22:40:33 INFO - PROCESS | 4730 | __metrics Screen width/height:1600/1200 22:40:33 INFO - PROCESS | 4730 | colorDepth:24 22:40:33 INFO - PROCESS | 4730 | Browser inner width/height: 1024/697 22:40:33 INFO - PROCESS | 4730 | __metrics 22:40:34 ERROR - PROCESS | 4730 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:40:36 INFO - TEST-INFO | 4730: exit 0 22:40:36 INFO - Browser initialized. 22:40:36 INFO - Running cycle 1/1 for kraken test... 22:40:36 INFO - TEST-INFO | started process 4811 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWmEzB6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:47 INFO - PROCESS | 4811 | RSS: Main: 156569600 22:40:47 INFO - PROCESS | 4811 | 22:40:50 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50126/tests/kraken/driver.html?testName=audio-beat-detection) 22:40:51 INFO - PROCESS | 4811 | RSS: Main: 214511616 22:40:51 INFO - PROCESS | 4811 | 22:40:53 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50126/tests/kraken/driver.html?testName=audio-dft) 22:40:54 INFO - PROCESS | 4811 | RSS: Main: 202022912 22:40:54 INFO - PROCESS | 4811 | 22:40:57 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50126/tests/kraken/driver.html?testName=audio-fft) 22:40:58 INFO - PROCESS | 4811 | RSS: Main: 268255232 22:40:58 INFO - PROCESS | 4811 | 22:41:00 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50126/tests/kraken/driver.html?testName=audio-oscillator) 22:41:00 INFO - PROCESS | 4811 | RSS: Main: 179036160 22:41:00 INFO - PROCESS | 4811 | 22:41:03 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50126/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:03 INFO - PROCESS | 4811 | RSS: Main: 183177216 22:41:03 INFO - PROCESS | 4811 | 22:41:06 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50126/tests/kraken/driver.html?testName=imaging-darkroom) 22:41:07 INFO - PROCESS | 4811 | RSS: Main: 217800704 22:41:07 INFO - PROCESS | 4811 | 22:41:10 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50126/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:11 INFO - PROCESS | 4811 | RSS: Main: 220266496 22:41:11 INFO - PROCESS | 4811 | 22:41:13 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50126/tests/kraken/driver.html?testName=json-parse-financial) 22:41:14 INFO - PROCESS | 4811 | RSS: Main: 220143616 22:41:14 INFO - PROCESS | 4811 | 22:41:16 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50126/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:16 INFO - PROCESS | 4811 | RSS: Main: 207192064 22:41:16 INFO - PROCESS | 4811 | 22:41:18 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:19 INFO - PROCESS | 4811 | RSS: Main: 210362368 22:41:19 INFO - PROCESS | 4811 | 22:41:21 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:41:21 INFO - PROCESS | 4811 | RSS: Main: 216457216 22:41:21 INFO - PROCESS | 4811 | 22:41:24 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:41:25 INFO - PROCESS | 4811 | RSS: Main: 222564352 22:41:25 INFO - PROCESS | 4811 | 22:41:27 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:41:28 INFO - PROCESS | 4811 | RSS: Main: 223260672 22:41:28 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | Cycle 1(1): loaded http://localhost:50126/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50126/tests/kraken/driver.html?testName=ai-astar) 22:41:30 INFO - PROCESS | 4811 | RSS: Main: 266248192 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | __start_tp_report 22:41:30 INFO - PROCESS | 4811 | _x_x_mozilla_page_load 22:41:30 INFO - PROCESS | 4811 | _x_x_mozilla_page_load_details 22:41:30 INFO - PROCESS | 4811 | |i|pagename|runs| 22:41:30 INFO - PROCESS | 4811 | |0;ai-astar;110;106;110;108;109;111;109;109;109;110 22:41:30 INFO - PROCESS | 4811 | |1;audio-beat-detection;153;124;131;175;132;134;150;124;155;124 22:41:30 INFO - PROCESS | 4811 | |2;audio-dft;160;160;161;161;162;160;160;162;161;160 22:41:30 INFO - PROCESS | 4811 | |3;audio-fft;76;74;76;129;75;75;74;75;100;75 22:41:30 INFO - PROCESS | 4811 | |4;audio-oscillator;84;85;119;84;84;83;127;84;85;139 22:41:30 INFO - PROCESS | 4811 | |5;imaging-gaussian-blur;117;116;116;117;117;117;117;117;116;117 22:41:30 INFO - PROCESS | 4811 | |6;imaging-darkroom;145;145;146;146;146;145;146;146;146;145 22:41:30 INFO - PROCESS | 4811 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 22:41:30 INFO - PROCESS | 4811 | |8;json-parse-financial;53;52;53;53;52;53;59;53;53;52 22:41:30 INFO - PROCESS | 4811 | |9;json-stringify-tinderbox;60;62;62;61;62;61;62;62;63;67 22:41:30 INFO - PROCESS | 4811 | |10;stanford-crypto-aes;78;79;79;77;80;80;80;83;113;80 22:41:30 INFO - PROCESS | 4811 | |11;stanford-crypto-ccm;130;132;123;120;117;141;133;122;135;127 22:41:30 INFO - PROCESS | 4811 | |12;stanford-crypto-pbkdf2;163;164;165;162;161;164;170;165;164;161 22:41:30 INFO - PROCESS | 4811 | |13;stanford-crypto-sha256-iterative;63;63;65;63;67;69;62;63;74;62 22:41:30 INFO - PROCESS | 4811 | __end_tp_report 22:41:30 INFO - PROCESS | 4811 | __start_cc_report 22:41:30 INFO - PROCESS | 4811 | _x_x_mozilla_cycle_collect,1607 22:41:30 INFO - PROCESS | 4811 | __end_cc_report 22:41:30 INFO - PROCESS | 4811 | __startTimestamp1465105290308__endTimestamp 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | ------- Summary: start ------- 22:41:30 INFO - PROCESS | 4811 | Number of tests: 14 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:1.37 (1.3%) stddev-sans-first:1.41 22:41:30 INFO - PROCESS | 4811 | Values: 110.0 106.0 110.0 108.0 109.0 111.0 109.0 109.0 109.0 110.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:142.00 stddev:17.22 (12.1%) stddev-sans-first:17.63 22:41:30 INFO - PROCESS | 4811 | Values: 153.0 124.0 131.0 175.0 132.0 134.0 150.0 124.0 155.0 124.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 22:41:30 INFO - PROCESS | 4811 | Values: 160.0 160.0 161.0 161.0 162.0 160.0 160.0 162.0 161.0 160.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:18.02 (24.0%) stddev-sans-first:18.93 22:41:30 INFO - PROCESS | 4811 | Values: 76.0 74.0 76.0 129.0 75.0 75.0 74.0 75.0 100.0 75.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:84.00 stddev:21.87 (26.0%) stddev-sans-first:22.66 22:41:30 INFO - PROCESS | 4811 | Values: 84.0 85.0 119.0 84.0 84.0 83.0 127.0 84.0 85.0 139.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:41:30 INFO - PROCESS | 4811 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:41:30 INFO - PROCESS | 4811 | Values: 145.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:41:30 INFO - PROCESS | 4811 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:2.06 (3.9%) stddev-sans-first:2.18 22:41:30 INFO - PROCESS | 4811 | Values: 53.0 52.0 53.0 53.0 52.0 53.0 59.0 53.0 53.0 52.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:1.87 (3.0%) stddev-sans-first:1.81 22:41:30 INFO - PROCESS | 4811 | Values: 60.0 62.0 62.0 61.0 62.0 61.0 62.0 62.0 63.0 67.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:80.00 stddev:10.69 (13.4%) stddev-sans-first:11.19 22:41:30 INFO - PROCESS | 4811 | Values: 78.0 79.0 79.0 77.0 80.0 80.0 80.0 83.0 113.0 80.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:131.00 stddev:7.53 (5.7%) stddev-sans-first:7.95 22:41:30 INFO - PROCESS | 4811 | Values: 130.0 132.0 123.0 120.0 117.0 141.0 133.0 122.0 135.0 127.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.90 Median:164.00 stddev:2.60 (1.6%) stddev-sans-first:2.74 22:41:30 INFO - PROCESS | 4811 | Values: 163.0 164.0 165.0 162.0 161.0 164.0 170.0 165.0 164.0 161.0 22:41:30 INFO - PROCESS | 4811 | 22:41:30 INFO - PROCESS | 4811 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:64.00 stddev:3.87 (6.0%) stddev-sans-first:4.03 22:41:30 INFO - PROCESS | 4811 | Values: 63.0 63.0 65.0 63.0 67.0 69.0 62.0 63.0 74.0 62.0 22:41:30 INFO - PROCESS | 4811 | -------- Summary: end -------- 22:41:30 INFO - PROCESS | 4811 | 22:41:31 INFO - TEST-INFO | 4811: exit 0 22:41:31 INFO - TEST-OK | kraken | took 63163ms 22:41:31 INFO - SUITE-END | took 1180s 22:41:31 INFO - Completed test suite (00:19:40) 22:41:31 INFO - javascript benchmark 22:41:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.052044126541, 3121.360121175158, 3124.3282694220743, 3162.5730526378934, 3172.3020935394206, 5265.76304066317, 5340.706261155635, 5357.062514060033, 5438.700871452069, 5487.149491828768, 5437.075697131488, 5447.373552041517, 5488.890222195554, 5493.615446918708, 5548.4174161713045, 4895.828645997391, 4950.183219768734, 4984.551390374865, 5002.0, 5078.923816142742, 4984.77568509418, 5008.774605142778, 5029.270755740416, 5045.318881950945, 5054.469280725526, 4759.690620109682, 4886.0, 4887.291342755316, 4906.558409743122, 4948.406191257054, 4849.175640141185, 4863.19757240055, 4952.678075925053, 4960.851174464736, 5007.4992500749995, 4728.148933192058, 4902.268682608774, 4919.163742163841, 4939.8024079036795, 4980.900381992376, 5275.683458992473, 5351.143816989302, 5366.70483123428, 5406.297181366397, 5436.646617969819, 5826.708664566755, 5829.8251052468495, 5846.9707651461895, 5852.034414321632, 5860.619059761145, 5625.8593535161535, 5789.594728369016, 5799.623024503394, 5914.911276330816, 5961.284645842483, 5473.917891231635, 5476.890462190774, 5492.176173573956, 5495.120780675073, 5542.224088627595, 6046.338374431997, 6081.452669259788, 6091.360407157274, 6094.634321940609, 6097.7865774697675, 5448.673079615236, 5728.226689396962, 5784.016717158093, 5791.7683292667825, 5821.38875418075, 5917.467427931507, 5964.821055368346, 5972.014617588136, 5972.731227094791, 5977.581569290108, 5878.676672783038, 5880.353161152269, 5884.764609415661, 5885.176075349455, 5891.793787217427, 5522.0, 5774.393688662714, 5807.419258074159, 5849.034909240013, 5896.970515147483, 4457.665675074382, 4460.1971645104195, 4461.888452788707, 4465.486469056035, 4506.031203291308, 3827.693784497289, 3935.960640393617, 3979.045029192973, 3996.6403023728003, 4021.1354558770004, 5349.090654588729, 5404.162354835007, 5417.6478528895495, 5419.214213938957, 5439.3200849893765, 5869.735861886225, 5876.676782776988, 5878.47093761555, 5880.0591905295805, 5923.0, 5254.448282930313, 5406.729663516887, 5441.727913604304, 5448.564114870801, 5452.454754524595, 5037.672551284155, 5065.949340506621, 5069.493050694901, 5070.898582028339, 5114.258432527263, 6101.420365065312, 6108.633481991094, 6111.419415155553, 6112.7554897804475, 6114.2662880453745, 5608.915866262091, 5704.030314846403, 5766.942330576809, 5911.852203694942, 5941.257342832146, 5954.523638109027, 5958.821235362946, 5963.2844058713235, 5964.194833697396, 6006.309274433322, 5676.148577713376, 5706.771729130955, 5714.542836573148, 5717.0, 5727.8568035799435, 5647.576431767716, 5797.594168408171, 5826.213461182686, 5830.737616807169, 5850.444207800252, 5730.341010783646, 5738.569607279387, 5738.8278351649515, 5744.942550574441, 5746.9712651436475, 5648.887022259614, 5687.658740475585, 5699.772009119754, 5699.971500142473, 5701.087825947994, 5445.0, 5545.972270138624, 5585.720713964367, 5615.18579805934, 5635.239242702182, 5734.311882574118, 5748.48263656273, 5781.190633311424, 5784.161296612052, 5791.276090488689, 5382.434844341445, 5558.833235002957, 5604.8038318658655, 5612.0, 5642.85892852682], "value": 5366.642915729373, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.4126652714021, 623.149401067543, 635.1045026512617, 635.3455940381407, 643.1606753187089, 32182.839085804568, 32763.67236327639, 34919.65080349199, 34944.30111397771, 34954.65045349546, 44506.0, 45575.54424455763, 45585.316220256726, 49298.75350623242, 49348.75325623376, 8772.42979206351, 8896.59965301563, 8901.376903616749, 8903.198712115909, 8933.196012358887, 8695.69565065223, 8806.64773409063, 8814.603342849556, 8828.028916819145, 8829.60266787996, 8799.560021998906, 8845.248153906925, 8853.070427605106, 8862.822743545124, 8865.822683546325, 8768.386212965095, 8824.294056475466, 8829.602667879928, 8831.425957312755, 8832.0, 8798.912010879872, 8893.955530222373, 8911.55442227886, 8919.821603567892, 8921.241694455979, 40734.79632601818, 40846.38730419046, 41005.1798964022, 41007.589924100976, 41047.7947610263, 3824.2733880562882, 3853.3064048471265, 4133.81397837098, 4285.121550082225, 4294.0, 33252.966716338786, 33535.0, 34008.82995585031, 34152.48771268433, 34593.6540634593, 4358.346248062784, 4407.853703565115, 4444.088961762864, 4458.219811532968, 4492.16894874446, 353092.0, 353148.0, 353208.0, 353829.0, 355353.2232338848, 59823.0, 60413.37519748829, 60711.0, 60735.0, 60864.0, 63143.36856631375, 63729.681351592946, 63920.36079639144, 63926.36073639204, 63926.36073639297, 15820.288087036111, 15923.12422816733, 15932.123733194785, 15939.760903586224, 15950.202489875459, 13549.458021679022, 13594.932025340007, 13635.250061246528, 13638.727225455435, 13639.727205456034, 17203.913980430018, 17428.651426971388, 17469.21388537519, 17469.7379539307, 17551.385701500643, 5773.690523790433, 5871.178035075093, 6000.909986350208, 6005.207578475943, 6017.638941663514, 993.9105480506645, 1096.4188979840696, 1104.1885751701468, 1121.3664279681955, 1146.896779289868, 32843.50734738981, 32916.83541582277, 33081.83459082689, 33092.33815323728, 33759.31203439818, 1099.5217080569978, 1121.6803211084964, 1190.7021346732104, 1209.087139209891, 1233.5189276182296, 17141.228644711016, 17198.65602687939, 17226.0, 17321.133943302786, 17333.306667733403, 510188.0, 510702.0, 513053.0, 514188.0, 515347.0, 59693.0, 59755.402445974985, 59966.700166498886, 60007.0, 60030.0, 1270.1934271737514, 1396.4484028808563, 1401.3063533550753, 1430.0990376063014, 1432.5630682641895, 32960.83519582387, 33691.32617347688, 34491.13772155716, 35043.12392190113, 36307.45538816973, 57992.71003644955, 58676.0, 58887.70556147192, 58944.41055589561, 58982.0, 503738.0, 508047.0, 511423.0, 513229.0, 513687.0, 27717.30706732348, 28406.43187136287, 28414.715852841207, 28546.000889968753, 29829.403411932075, 49195.754021229666, 49329.75335123301, 49465.03209807423, 50727.0, 50998.23502647538, 41398.58601414068, 42278.0, 42758.78620607001, 42913.78543107265, 43729.56270437255, 41457.1708565833, 41703.37444938389, 41730.374044389966, 42451.15097697967, 42713.145737084466, 41541.37687934744, 41567.0, 42260.0, 42418.78790606027, 42747.0, 35906.0, 37703.00761201083, 37915.0, 38302.808485957394, 38479.0, 28212.435751284447, 28341.874359422713, 28470.152578789435, 28585.85707071451, 28873.711262887104], "value": 20451.88876309128, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.26040523006264, 390.77335145615535, 405.9553449120596, 411.69123157631776, 422.1704350950382, 18062.09689515524, 18522.166502507385, 19519.902400487994, 19548.511287217825, 19638.410847674593, 18290.542736431595, 18350.816491835078, 18414.079296035212, 18626.25494980199, 18961.14674839632, 5502.312210973629, 5642.238297829782, 5740.56945729072, 5746.741396637161, 5837.854053648651, 5765.1352297155345, 5765.481106700396, 5771.278590176228, 5799.681017544033, 5808.0, 5593.04918163911, 5966.850828729273, 6220.813375598716, 6289.779857704982, 6385.393387628168, 5704.458076482769, 6146.416090471398, 6285.465735412496, 6308.526860485459, 6322.1465102211305, 5514.641548299388, 5612.214289999403, 5641.323041235078, 5644.153376993443, 5654.123610840326, 13423.597292081253, 14579.635509112251, 22157.66763498549, 22270.554588908293, 22293.66559501609, 2139.8181154601875, 2220.200727738029, 2402.147237730598, 2649.3509090273005, 2879.0, 9373.671921482784, 9400.952995235048, 9464.148226659634, 9760.219182465386, 9976.551055202533, 1863.3943968210385, 1910.646530391868, 1940.185122248659, 1959.2358979997812, 1992.8903910285073, 81969.0, 82340.17659823385, 82646.58676706639, 82911.585442073, 82932.0, 10747.0, 10819.675409737563, 10948.397838118979, 11065.668029959113, 11257.71855703614, 15329.770053449207, 15330.846691532943, 15382.769258461132, 15383.077015378889, 15397.07617542951, 7860.764177074697, 8219.671213151407, 8233.876491852627, 8340.874886876702, 8412.873806892901, 7866.9606651967515, 7884.684612615546, 7922.841543169219, 8080.959595201986, 8100.756977290573, 4421.602055814992, 4760.904991851824, 4777.665563410597, 4808.831690890802, 4813.7352445615825, 1944.4069558784424, 1951.1122439290089, 2002.8698134621202, 2017.3141132015185, 2017.8587498874938, 856.3834039491556, 925.1812146250617, 1075.8493810866485, 1081.551156270139, 1100.8238681810872, 17362.826371736122, 17933.282668693108, 18269.54326141857, 18845.623087538446, 18896.527586810163, 991.8908920018793, 997.7904640025675, 1009.6365308488938, 1014.3102690170607, 1039.7504598896362, 8666.263367613823, 10447.634332798316, 10452.843207351896, 10528.947355263328, 10558.947205264078, 504155.0, 505980.0, 518393.0, 522432.0, 535990.0, 89019.0, 90449.54775226214, 91271.54364228269, 92474.07525924654, 92597.0, 1003.1623594298842, 1014.7260239735231, 1043.5617040842865, 1061.6974162363845, 1062.2829590026702, 16342.509724708278, 16571.368646035764, 16649.833501664827, 16711.331546738482, 16979.151042447582, 29570.0, 35478.113047174025, 39035.41446878242, 40160.59839401569, 40649.79675101605, 55227.72386138044, 55844.16233756416, 55864.162037570284, 56397.0, 57635.7118214423, 7480.775576732707, 7714.498557593738, 8051.8792218115605, 8521.786955326166, 8631.95684021601, 12855.550055748377, 13016.869831301567, 13293.401796919356, 13313.267770272729, 13421.798673019717, 11331.376774277494, 11332.433378331216, 11576.0, 11992.280463172237, 12100.636980890587, 11335.319880807177, 11500.94249528747, 11531.3081215124, 11646.184767066225, 11763.117766167743, 11752.941235293769, 11864.22882512634, 12094.45574949147, 12192.207506512048, 12324.260544367367, 7345.632718364167, 7640.503367281109, 7860.0, 7893.210678932061, 7950.363970882431, 10296.536655850356, 11940.641780746591, 12050.819237711617, 12184.329861857683, 12194.939025304817], "value": 9122.790674060014, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.8909687225023, 1481.2963842174966, 1533.1261181126756, 1683.7306031035037, 1824.7901491328494, 8281.0, 8591.441556298836, 8601.870971935417, 8624.0, 8818.0, 8229.958850205756, 8395.91604083959, 8664.480131192133, 8694.782630434242, 8714.0, 2039.9286024989128, 2274.749777524471, 2318.385627808632, 2339.532093581282, 2370.1349007612184, 2379.500304935957, 2394.1500767227644, 2395.688560487134, 2396.281115665302, 2399.9280021599293, 2317.0963324303452, 2336.6495025746103, 2348.0725113580124, 2353.4587044979667, 2353.752855950126, 1940.4275738657059, 2164.4372463159534, 2239.3393948785147, 2246.5057687308768, 2260.7965283124518, 2309.8845057747044, 2324.9883750581307, 2328.7089113860766, 2347.319277409549, 2349.6475528670667, 8751.606177722018, 8781.560921953876, 8798.912010879872, 8802.559872006437, 8807.515586642732, 5200.765965531522, 5319.6808191508635, 5333.599980001515, 5339.065663508871, 5360.383555891083, 3244.886428974998, 3326.5176549400203, 3376.5610470638658, 3390.3897298486263, 3503.036664917143, 1133.8922802333766, 1153.988460115405, 1154.1632316570503, 1189.2032338333252, 1194.713268815478, 12419.75160496794, 12617.864392204838, 12624.747505049847, 12665.430055647517, 12674.556390526288, 4474.351219073216, 4589.380433641416, 4657.441107067174, 4675.719456832601, 4677.836275730401, 10658.360498370123, 10687.83968240477, 10692.62575809843, 10694.144468442506, 10697.465126743631, 5258.474152584788, 5301.390340110936, 5305.098133317345, 5308.124159513714, 5308.681479111266, 4166.500019997559, 4751.59611433032, 4860.975695121501, 4872.878178045577, 4945.307656928033, 4048.6712510651746, 4069.0, 4088.4684990950987, 4144.274751918402, 4157.064660451374, 1264.3235868810143, 1265.4938024790158, 1266.1200465676266, 1288.6842723532795, 1293.5731208701106, 1032.9690109296732, 1034.5603118674683, 1039.8076355874186, 1045.639254457211, 1046.9005444482762, 6036.245469316335, 6099.939000610026, 6164.167837341902, 6215.81352559424, 6319.146915166486, 465.58694362609316, 473.2073776424475, 479.69779039205076, 479.93280940668336, 483.0556262506791, 4148.979255103705, 4232.174725928476, 4329.437173167531, 4343.109662519176, 4395.758233297135, 10651.254412191227, 11164.385958772345, 11175.664730058112, 11216.495257713423, 11246.775064498665, 10357.792844143074, 10694.786104277871, 10750.516226769814, 11099.278546894426, 11134.721631959266, 8359.456635318686, 8431.536265505334, 8491.320694344438, 8491.448055876348, 8549.743507694655, 6075.665838378932, 6298.244210694746, 6338.683065846782, 6347.523935704933, 6376.489880809617, 10252.846207306748, 10397.740056498647, 10507.211959103252, 10515.421651808918, 10517.05346518817, 9856.162226210852, 10252.948735256276, 10278.743031424274, 10507.89492105069, 10538.315009524356, 7036.753713619999, 7511.887321690069, 7669.808254793675, 7845.3723702104835, 8039.959800200962, 8535.615897284513, 8546.401751877558, 8604.956975215084, 8613.26787223093, 8626.18051285127, 7297.525660832029, 7598.0502437195355, 7644.770656880302, 7736.187700291388, 7801.0, 7405.925940740523, 7486.213947535431, 7542.924570754222, 7558.055243094614, 7713.498622589514, 6725.39471447572, 6773.322667733188, 6805.3875151236625, 6807.761728339485, 6813.080234168324, 4900.117978763857, 4969.0, 5026.145555255543, 5189.091908916, 5194.35070616173, 4926.285688575065, 5075.87310317245, 5206.479352064764, 5276.5251127398715, 5282.524572788468], "value": 4588.837139876542, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.476349749145, 333.87312821127966, 338.9898303050906, 341.85642030347253, 342.9916046822342, 5266.7629956651945, 5291.68249905006, 5298.708571028592, 5329.786808527664, 5334.199870019499, 5207.270982062505, 5217.57382816991, 5222.738863056851, 5226.18994055921, 5230.947690523094, 3949.743266687656, 3970.5235371755284, 4075.347944328893, 4106.568810274923, 4116.156187981456, 4032.7580345179235, 4057.1885622875398, 4058.715889887709, 4070.287699652564, 4081.7755023473696, 3903.765774053566, 4024.7585144891254, 4073.450084238634, 4078.7552746835136, 4080.6735461163034, 3786.1859700164596, 4010.418489319047, 4039.9192016159805, 4055.2092341993325, 4062.6952978526433, 3610.8194590270646, 3627.6372362763777, 3642.6357364263627, 3643.872464463731, 3648.908777280563, 4942.258661200813, 5015.924761128586, 5046.7476626168545, 5048.697078175284, 5073.365829271341, 4495.550444955511, 4611.44662640482, 4627.838025669085, 4675.485696573374, 4718.433787945434, 5341.679499230058, 5347.732613369316, 5373.919391209135, 5394.217838413448, 5407.215953686733, 3959.821808018646, 3965.306071437487, 3965.7025723070883, 3970.3250447424007, 3986.4219688145345, 5382.30030096085, 5610.204734484925, 5616.887662246773, 5633.830985070414, 5682.431756824326, 3913.8434462621744, 3950.170464202492, 3960.980195099006, 3964.861229856941, 3982.3628219484744, 4007.3970411834916, 4007.73949693269, 4023.6982226333143, 4057.594240575919, 4064.435192450928, 4465.12929978651, 4621.792019359137, 4637.188492013884, 4651.883702907455, 4670.696404733681, 5458.536024437967, 5465.289512363367, 5465.977588292358, 5471.699056551848, 5483.972580137153, 1375.628580283338, 1379.406855052337, 1380.42712274407, 1386.7573174694387, 1394.5537428022935, 852.9849479119828, 893.9508327042075, 895.8790563273875, 909.3589019741066, 920.2316066084894, 5104.923426148611, 5154.201098829687, 5259.973700131475, 5272.446393128666, 5286.577073834084, 5592.133219351007, 5608.46719561641, 5644.858878527987, 5652.7173641318595, 5652.830415087554, 4925.827596034122, 5145.202493613496, 5228.398734145546, 5266.183741519994, 5290.672104274107, 2943.484890144216, 2956.2757124504205, 2977.196157037631, 2980.344324248662, 3002.6697063323018, 5773.682447465346, 5823.893460242541, 5825.621334613236, 5837.503812175928, 5844.094165404369, 5575.275214222125, 5687.43125687428, 5719.7712091516705, 5730.914036289459, 5755.8561035974435, 5766.221560089418, 5782.669985903266, 5786.189933409326, 5798.5651076168615, 5816.680082595498, 5319.749858783105, 5567.721613919369, 5613.0, 5619.8876022480135, 5633.492985631313, 5496.148097044965, 5506.91739623898, 5543.974364742536, 5561.388247292875, 5565.332160140809, 5732.34078081026, 5776.631581051749, 5780.479756821906, 5791.247137872049, 5797.768089276382, 3595.244998550229, 3675.7794532328144, 3688.5573731152435, 3705.999380167286, 3711.758735682172, 4980.708873918038, 4983.775730092227, 4994.2520117959875, 5009.223570346602, 5049.37432535087, 4831.468538460696, 4910.2880082388565, 4947.356843610308, 4952.439981405845, 4958.107540642719, 4612.953870461387, 4830.686005409637, 4885.413750349981, 4914.262860570943, 4950.925736113889, 4767.330295034792, 4787.329773831736, 4798.480797581297, 4868.586170175433, 5138.229265610188, 5343.686914113275, 5452.918206226989, 5484.287042684426, 5514.09017512106, 5522.282103326542, 5288.68267903927, 5311.973440132774, 5313.388960269618, 5332.920006199833, 5373.4357892422295], "value": 4103.457007319056, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.390696187355, 718.3068339052813, 757.0423414380809, 809.9352051835851, 823.7611092783093, 14443.78334324986, 15083.622909427257, 15381.23093845306, 15405.922970385147, 15409.537713868602, 13806.309684515783, 13920.651983700413, 13934.581962541115, 13936.0, 13938.581842544714, 671.8723442545901, 790.0637744273026, 802.5024484819405, 813.63386476086, 816.4121832280748, 805.1948051948052, 809.9028116626013, 811.4157806379427, 812.2608426332039, 812.5206128384252, 861.104451370577, 861.9741407757746, 862.6463150108457, 862.8533149364591, 862.9870551941726, 710.133636962907, 832.7335252719132, 851.4805968359295, 855.055164043732, 856.4390324337569, 626.1765778001912, 626.6960524145786, 627.0468887291337, 627.5983370642792, 627.8399008252866, 13800.585982420544, 13818.723625527533, 13826.930865345608, 13828.516001939985, 13832.515861944885, 12263.099219620884, 12412.006476522605, 12477.50089996399, 12644.051696122735, 12852.036097292741, 10312.845307320396, 10639.521221545048, 10898.073663738593, 11251.662450126509, 11378.487968041458, 4348.673849461272, 4375.606195442394, 4377.190219809346, 4468.329750537413, 4591.127685739699, 77888.61055694742, 78291.0, 78682.0, 78711.21288787153, 79385.20614793836, 10622.628208012682, 10777.515011824487, 10852.077573406192, 10868.239223254297, 10945.4527273636, 18737.812621873607, 18758.906205469157, 18767.530811729815, 18767.718484222747, 18793.0, 8043.316318112909, 8056.264084594286, 8090.150534193943, 8117.512949223065, 8318.8336233275, 4648.48866624671, 4671.229247174246, 4686.8125274988615, 4730.455997560324, 4777.307290442866, 3306.669333066674, 3393.5927688677025, 3394.8472318745717, 3403.1151900506047, 3404.029851492313, 597.3608239184118, 606.4087514673247, 622.3030206169119, 625.0686477149106, 691.0705101638289, 622.1787240842045, 638.1990601794718, 646.7348387161242, 647.9676016199171, 699.5977313044917, 9219.90780092204, 9406.0, 9430.62277508892, 9694.079062489052, 9759.658411955548, 587.5358466811172, 669.9464042876558, 693.8508220732567, 705.1855107351017, 712.3624356201171, 4263.614076788811, 4394.670399720034, 4486.327050942385, 4521.073179998092, 4543.159515489645, 76070.0, 77475.0, 77810.6109469449, 93753.0, 119494.0, 101425.0, 110738.44630776794, 111308.0, 111497.44251278853, 111915.80841915743, 95708.52145739227, 96286.0371396291, 97712.51143744378, 98588.50705746569, 100245.99754002658, 8673.132686731276, 8702.216800487986, 8882.578787393986, 8905.0, 8921.068652009379, 43718.0, 44468.55531444774, 55005.0, 56355.0, 57442.7127864358, 75109.24890751022, 75244.0, 75391.0, 75870.24129758852, 76551.0, 12641.0, 12756.61730148097, 12788.0, 13139.343032848128, 13397.171873373894, 12798.680032998876, 13004.21974681522, 13642.052565706867, 13680.65798355049, 14087.859121408655, 12482.625521234377, 12724.491020359452, 12737.681557960754, 12908.290044047291, 12974.870251297743, 11915.52337906474, 12119.333436660716, 12262.877371226174, 12548.309842958724, 12551.874481255436, 11958.402079895797, 12020.63938081859, 12112.939435302767, 12458.937705311415, 12737.108402411743, 9756.317057805885, 9764.170045546209, 9767.902320976984, 9838.212942964688, 10255.487225638837, 9081.137291957253, 9452.480113593818, 9458.43249405038, 10038.749031273985, 10309.432981186106], "value": 7251.4117448843335, "unit": "score"}], "name": "dromaeo_css", "value": 7177.628919054518, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 110.0, 108.0, 109.0, 111.0, 109.0, 109.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 131.0, 175.0, 132.0, 134.0, 150.0, 124.0, 155.0, 124.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 162.0, 160.0, 160.0, 162.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 129.0, 75.0, 75.0, 74.0, 75.0, 100.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 119.0, 84.0, 84.0, 83.0, 127.0, 84.0, 85.0, 139.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 53.0, 52.0, 53.0, 59.0, 53.0, 53.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0, 63.0, 67.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 77.0, 80.0, 80.0, 80.0, 83.0, 113.0, 80.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 123.0, 120.0, 117.0, 141.0, 133.0, 122.0, 135.0, 127.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 165.0, 162.0, 161.0, 164.0, 170.0, 165.0, 164.0, 161.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 63.0, 67.0, 69.0, 62.0, 63.0, 74.0, 62.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1503.1000000000001, "alertThreshold": 2.0}]} 22:41:31 INFO - Return code: 0 22:41:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:41:31 ERROR - # TBPL SUCCESS # 22:41:31 INFO - Running post-action listener: _resource_record_post_action 22:41:31 INFO - ##### 22:41:31 INFO - ##### Finished run-tests step (success) 22:41:31 INFO - ##### 22:41:31 INFO - Running post-run listener: _resource_record_post_run 22:41:31 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 539664384; Read time: 16; Write time: 1871424 22:41:31 INFO - TinderboxPrint: CPU usage
13.3% 22:41:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:41:31 INFO - TinderboxPrint: I/O write bytes / time
539,664,384 / 1,871,424 22:41:31 INFO - TinderboxPrint: CPU idle
8,298.7 (86.6%) 22:41:31 INFO - TinderboxPrint: CPU user
1,211.3 (12.6%) 22:41:31 INFO - TinderboxPrint: Swap in / out
0 / 0 22:41:31 INFO - install - Wall time: 19s; CPU: 18.0%; Read bytes: 0; Write bytes: 248741888; Read time: 0; Write time: 951144 22:41:31 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290922496; Read time: 16; Write time: 920280 22:41:32 INFO - Running post-run listener: _upload_blobber_files 22:41:32 INFO - Blob upload gear active. 22:41:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:41:32 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 22:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:41:32 INFO - (blobuploader) - INFO - Open directory for files ... 22:41:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:41:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:41:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:37 INFO - (blobuploader) - INFO - Done attempting. 22:41:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:41:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:41:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:38 INFO - (blobuploader) - INFO - Done attempting. 22:41:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:41:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:41:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:39 INFO - (blobuploader) - INFO - Done attempting. 22:41:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:41:39 INFO - Return code: 0 22:41:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:41:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:41:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/558a2da6ba3417a3416008c539d587ffadfc66394039318c1ec1dd00f1ea80444d0389472bc5601d9a1179b51e55a79d9a2b35d1745ab94ce97dc8089e2b59a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de75d6f1b3eba1cd7d3fd1781060b42be0867bb2269b2833425708cb62bcf8bd50a7c220dca4099e6879c94105469e7ee5cc5d5997e68ee54de6f363fc892799", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b59f511d750e43b38c6c32e26c9fef3da5b461d8c9a56a7f08799303e55a8b1d69c8823f88696d1190cd9338e570e8e31c5327128d1869adc142a746e4de0687"} 22:41:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:41:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:41:39 INFO - Contents: 22:41:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/558a2da6ba3417a3416008c539d587ffadfc66394039318c1ec1dd00f1ea80444d0389472bc5601d9a1179b51e55a79d9a2b35d1745ab94ce97dc8089e2b59a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de75d6f1b3eba1cd7d3fd1781060b42be0867bb2269b2833425708cb62bcf8bd50a7c220dca4099e6879c94105469e7ee5cc5d5997e68ee54de6f363fc892799", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b59f511d750e43b38c6c32e26c9fef3da5b461d8c9a56a7f08799303e55a8b1d69c8823f88696d1190cd9338e570e8e31c5327128d1869adc142a746e4de0687"} 22:41:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:41:39 INFO - Copying logs to upload dir... 22:41:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:41:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.309941 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-04 22:41:39.101469) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:39.104880) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/558a2da6ba3417a3416008c539d587ffadfc66394039318c1ec1dd00f1ea80444d0389472bc5601d9a1179b51e55a79d9a2b35d1745ab94ce97dc8089e2b59a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de75d6f1b3eba1cd7d3fd1781060b42be0867bb2269b2833425708cb62bcf8bd50a7c220dca4099e6879c94105469e7ee5cc5d5997e68ee54de6f363fc892799", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b59f511d750e43b38c6c32e26c9fef3da5b461d8c9a56a7f08799303e55a8b1d69c8823f88696d1190cd9338e570e8e31c5327128d1869adc142a746e4de0687"} build_url:https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011449 build_url: 'https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/558a2da6ba3417a3416008c539d587ffadfc66394039318c1ec1dd00f1ea80444d0389472bc5601d9a1179b51e55a79d9a2b35d1745ab94ce97dc8089e2b59a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de75d6f1b3eba1cd7d3fd1781060b42be0867bb2269b2833425708cb62bcf8bd50a7c220dca4099e6879c94105469e7ee5cc5d5997e68ee54de6f363fc892799", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b59f511d750e43b38c6c32e26c9fef3da5b461d8c9a56a7f08799303e55a8b1d69c8823f88696d1190cd9338e570e8e31c5327128d1869adc142a746e4de0687"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:39.129963) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:39.130334) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1465082086.447828-1547409679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:39.214240) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:41:39.214628) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:41:39.214934) ========= ========= Total master_lag: 0.20 =========