builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1470268800.35 results: success (0) buildid: 20160803141223 builduid: 87a0ac5ce7c942a7bd252bf6f65d4026 revision: 02304924791d467a6efe240d9012c02d91fe4703 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.353278) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.353677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.353941) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.369106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.369382) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-03 17:00:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 143M=0s 2016-08-03 17:00:00 (143 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104570 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.487807) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.488112) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019934 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.537731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:00.538054) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02304924791d467a6efe240d9012c02d91fe4703 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02304924791d467a6efe240d9012c02d91fe4703 --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=78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-03 17:00:00,674 truncating revision to first 12 chars 2016-08-03 17:00:00,674 Setting DEBUG logging. 2016-08-03 17:00:00,674 attempt 1/10 2016-08-03 17:00:00,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/02304924791d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-03 17:00:00,979 unpacking tar archive at: mozilla-beta-02304924791d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664938 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:01.216441) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:01.216745) ========= script_repo_revision: 02304924791d467a6efe240d9012c02d91fe4703 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:01.217138) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:01.217419) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 17:00:01.227325) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-03 17:00:01.227655) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:00:01 INFO - MultiFileLogger online at 20160803 17:00:01 in /builds/slave/test 17:00:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:00:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:00:01 INFO - {'append_to_log': False, 17:00:01 INFO - 'base_work_dir': '/builds/slave/test', 17:00:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:00:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:00:01 INFO - 'branch': 'Mozilla-Beta', 17:00:01 INFO - 'buildbot_json_path': 'buildprops.json', 17:00:01 INFO - 'config_files': ('talos/linux_config.py',), 17:00:01 INFO - 'default_actions': ('clobber', 17:00:01 INFO - 'read-buildbot-config', 17:00:01 INFO - 'download-and-extract', 17:00:01 INFO - 'populate-webroot', 17:00:01 INFO - 'create-virtualenv', 17:00:01 INFO - 'install', 17:00:01 INFO - 'run-tests'), 17:00:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:00:01 INFO - 'download_minidump_stackwalk': True, 17:00:01 INFO - 'download_symbols': 'ondemand', 17:00:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:00:01 INFO - 'tooltool.py': '/tools/tooltool.py', 17:00:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:00:01 INFO - '/tools/misc-python/virtualenv.py')}, 17:00:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:00:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:00:01 INFO - 'installer_path': 'installer.exe', 17:00:01 INFO - 'log_level': 'info', 17:00:01 INFO - 'log_name': 'talos', 17:00:01 INFO - 'log_to_console': True, 17:00:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:00:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:00:01 INFO - 'opt_config_files': (), 17:00:01 INFO - 'pip_index': False, 17:00:01 INFO - 'sps_profile': False, 17:00:01 INFO - 'sps_profile_interval': 0, 17:00:01 INFO - 'suite': 'dromaeojs', 17:00:01 INFO - 'system_bits': '32', 17:00:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:00:01 INFO - 'title': 'talos-linux64-ix-019', 17:00:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:00:01 INFO - 'use_talos_json': True, 17:00:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:00:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:00:01 INFO - 'work_dir': 'build'} 17:00:01 INFO - ##### 17:00:01 INFO - ##### Running clobber step. 17:00:01 INFO - ##### 17:00:01 INFO - Running pre-action listener: _resource_record_pre_action 17:00:01 INFO - Running main action method: clobber 17:00:01 INFO - rmtree: /builds/slave/test/build 17:00:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:00:01 INFO - Running post-action listener: _resource_record_post_action 17:00:01 INFO - ##### 17:00:01 INFO - ##### Finished clobber step (success) 17:00:01 INFO - ##### 17:00:01 INFO - ##### 17:00:01 INFO - ##### Running read-buildbot-config step. 17:00:01 INFO - ##### 17:00:01 INFO - Running pre-action listener: _resource_record_pre_action 17:00:01 INFO - Running main action method: read_buildbot_config 17:00:01 INFO - Using buildbot properties: 17:00:01 INFO - { 17:00:01 INFO - "project": "", 17:00:01 INFO - "product": "firefox", 17:00:01 INFO - "script_repo_revision": "production", 17:00:01 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:00:01 INFO - "repository": "", 17:00:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:00:01 INFO - "buildid": "20160803141223", 17:00:01 INFO - "pgo_build": "True", 17:00:01 INFO - "basedir": "/builds/slave/test", 17:00:01 INFO - "buildnumber": 118, 17:00:01 INFO - "slavename": "talos-linux64-ix-019", 17:00:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:00:01 INFO - "platform": "ubuntu64_hw", 17:00:01 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:00:01 INFO - "branch": "mozilla-beta", 17:00:01 INFO - "repo_path": "releases/mozilla-beta", 17:00:01 INFO - "revision": "02304924791d467a6efe240d9012c02d91fe4703", 17:00:01 INFO - "stage_platform": "linux64-pgo", 17:00:01 INFO - "builduid": "87a0ac5ce7c942a7bd252bf6f65d4026", 17:00:01 INFO - "slavebuilddir": "test" 17:00:01 INFO - } 17:00:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 17:00:01 INFO - Running post-action listener: _resource_record_post_action 17:00:01 INFO - ##### 17:00:01 INFO - ##### Finished read-buildbot-config step (success) 17:00:01 INFO - ##### 17:00:01 INFO - ##### 17:00:01 INFO - ##### Running download-and-extract step. 17:00:01 INFO - ##### 17:00:01 INFO - Running pre-action listener: _resource_record_pre_action 17:00:01 INFO - Running main action method: download_and_extract 17:00:01 INFO - mkdir: /builds/slave/test/build/tests 17:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:01 INFO - https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:00:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 17:00:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 17:00:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 17:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:00:04 INFO - Downloaded 1327 bytes. 17:00:04 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 17:00:04 INFO - Using the following test package requirements: 17:00:04 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 17:00:04 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:00:04 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 17:00:04 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'jsshell-linux-x86_64.zip'], 17:00:04 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:00:04 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 17:00:04 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 17:00:04 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 17:00:04 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:00:04 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 17:00:04 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:00:04 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:00: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')]} 17:00:04 INFO - https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:00:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 17:00:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 17:00:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 17:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:00:05 INFO - Downloaded 21968535 bytes. 17:00:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:00:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:00:06 INFO - Return code: 0 17:00:06 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:06 INFO - https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:00:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 17:00:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 17:00:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 17:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:00:07 INFO - Downloaded 21968535 bytes. 17:00:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:00:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:00:08 INFO - Return code: 0 17:00: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')]} 17:00:08 INFO - https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:00:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 17:00:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 17:00:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 17:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:00:10 INFO - Downloaded 10833369 bytes. 17:00:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:00:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:00:10 INFO - Return code: 0 17:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:10 INFO - https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:00:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 17:00:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 17:00:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 17:00:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:00:12 INFO - Downloaded 55280261 bytes. 17:00:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 17:00:12 INFO - mkdir: /builds/slave/test/properties 17:00:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:00:12 INFO - Writing to file /builds/slave/test/properties/build_url 17:00:12 INFO - Contents: 17:00:12 INFO - build_url:https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 17:00:12 INFO - Running post-action listener: _resource_record_post_action 17:00:12 INFO - Running post-action listener: set_extra_try_arguments 17:00:12 INFO - ##### 17:00:12 INFO - ##### Finished download-and-extract step (success) 17:00:12 INFO - ##### 17:00:12 INFO - ##### 17:00:12 INFO - ##### Running populate-webroot step. 17:00:12 INFO - ##### 17:00:12 INFO - Running pre-action listener: _resource_record_pre_action 17:00:12 INFO - Running main action method: populate_webroot 17:00:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:00:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:00:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:00:12 INFO - '--tppagecycles', 17:00:12 INFO - '7'], 17:00:12 INFO - 'tests': ['tsvgm']}}, 17:00:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:00:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:00:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:00:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:00:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['damp', 'tps']}, 17:00:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'tests': ['damp', 'tps']}, 17:00:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['dromaeo_dom']}, 17:00:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:00:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['a11yr', 17:00:12 INFO - 'ts_paint', 17:00:12 INFO - 'tpaint', 17:00:12 INFO - 'sessionrestore', 17:00:12 INFO - 'sessionrestore_no_auto_restore', 17:00:12 INFO - 'tabpaint']}, 17:00:12 INFO - 'other-e10s': {'tests': ['a11yr', 17:00:12 INFO - 'ts_paint', 17:00:12 INFO - 'tpaint', 17:00:12 INFO - 'sessionrestore', 17:00:12 INFO - 'sessionrestore_no_auto_restore', 17:00:12 INFO - 'tabpaint']}, 17:00:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:00:12 INFO - 'ts_paint', 17:00:12 INFO - 'tpaint', 17:00:12 INFO - 'sessionrestore', 17:00:12 INFO - 'sessionrestore_no_auto_restore', 17:00:12 INFO - 'tabpaint']}, 17:00:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:00:12 INFO - 'ts_paint', 17:00:12 INFO - 'tpaint', 17:00:12 INFO - 'sessionrestore', 17:00:12 INFO - 'sessionrestore_no_auto_restore', 17:00:12 INFO - 'tabpaint']}, 17:00:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['a11yr', 17:00:12 INFO - 'ts_paint', 17:00:12 INFO - 'tpaint', 17:00:12 INFO - 'sessionrestore', 17:00:12 INFO - 'sessionrestore_no_auto_restore', 17:00:12 INFO - 'tabpaint']}, 17:00:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['a11yr', 17:00:12 INFO - 'ts_paint', 17:00:12 INFO - 'tpaint', 17:00:12 INFO - 'sessionrestore', 17:00:12 INFO - 'sessionrestore_no_auto_restore', 17:00:12 INFO - 'tabpaint']}, 17:00:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['tsvgx', 17:00:12 INFO - 'tsvgr_opacity', 17:00:12 INFO - 'tart', 17:00:12 INFO - 'tscrollx', 17:00:12 INFO - 'cart']}, 17:00:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:00:12 INFO - 'tsvgr_opacity', 17:00:12 INFO - 'tart', 17:00:12 INFO - 'tscrollx', 17:00:12 INFO - 'cart']}, 17:00:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'talos_options': ['--disable-e10s'], 17:00:12 INFO - 'tests': ['tp5o']}, 17:00:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'tests': ['tp5o']}, 17:00:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'talos_options': ['--disable-e10s', 17:00:12 INFO - '--xperf_path', 17:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:12 INFO - 'tests': ['tp5n']}, 17:00:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:12 INFO - 'talos_options': ['--xperf_path', 17:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:12 INFO - 'tests': ['tp5n']}}, 17:00:12 INFO - 'talos.zip': {'path': '', 17:00:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:00:12 INFO - Running post-action listener: _resource_record_post_action 17:00:12 INFO - ##### 17:00:12 INFO - ##### Finished populate-webroot step (success) 17:00:12 INFO - ##### 17:00:12 INFO - ##### 17:00:12 INFO - ##### Running create-virtualenv step. 17:00:12 INFO - ##### 17:00:12 INFO - Running pre-action listener: _resource_record_pre_action 17:00:12 INFO - Running main action method: create_virtualenv 17:00:12 INFO - Creating virtualenv /builds/slave/test/build/venv 17:00:12 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 17:00:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:00:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:00:12 INFO - Using real prefix '/usr' 17:00:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:00:13 INFO - Installing distribute.............................................................................................................................................................................................done. 17:00:15 INFO - Installing pip.................done. 17:00:15 INFO - Return code: 0 17:00:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:00:15 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')]} 17:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:15 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')]} 17:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:15 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:15 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 17:00:15 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 17:00:15 INFO - Using env: {'DISPLAY': ':0', 17:00:15 INFO - 'HOME': '/home/cltbld', 17:00:15 INFO - 'LANG': 'en_US.UTF-8', 17:00:15 INFO - 'LANGUAGE': 'en_US:en', 17:00:15 INFO - 'LOGNAME': 'cltbld', 17:00:15 INFO - 'MAIL': '/var/mail/cltbld', 17:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:15 INFO - 'MOZ_NO_REMOTE': '1', 17:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:15 INFO - 'NO_EM_RESTART': '1', 17:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:15 INFO - 'PWD': '/builds/slave/test', 17:00:15 INFO - 'SHELL': '/bin/bash', 17:00:15 INFO - 'SHLVL': '1', 17:00:15 INFO - 'TERM': 'linux', 17:00:15 INFO - 'TMOUT': '86400', 17:00:15 INFO - 'USER': 'cltbld', 17:00:15 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:15 INFO - '_': '/tools/buildbot/bin/python'} 17:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:15 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 17:00:15 INFO - Cleaning up... 17:00:15 INFO - Return code: 0 17:00:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:00:15 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')]} 17:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:15 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')]} 17:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:15 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:15 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 17:00:15 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 17:00:15 INFO - Using env: {'DISPLAY': ':0', 17:00:15 INFO - 'HOME': '/home/cltbld', 17:00:15 INFO - 'LANG': 'en_US.UTF-8', 17:00:15 INFO - 'LANGUAGE': 'en_US:en', 17:00:15 INFO - 'LOGNAME': 'cltbld', 17:00:15 INFO - 'MAIL': '/var/mail/cltbld', 17:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:15 INFO - 'MOZ_NO_REMOTE': '1', 17:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:15 INFO - 'NO_EM_RESTART': '1', 17:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:15 INFO - 'PWD': '/builds/slave/test', 17:00:15 INFO - 'SHELL': '/bin/bash', 17:00:15 INFO - 'SHLVL': '1', 17:00:15 INFO - 'TERM': 'linux', 17:00:15 INFO - 'TMOUT': '86400', 17:00:15 INFO - 'USER': 'cltbld', 17:00:15 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:15 INFO - '_': '/tools/buildbot/bin/python'} 17:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:15 INFO - Downloading/unpacking psutil>=3.1.1 17:00:15 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 17:00:15 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 17:00:15 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 17:00:15 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 17:00:15 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 17:00:15 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 17:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:00:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:17 INFO - Installing collected packages: psutil 17:00:17 INFO - Running setup.py install for psutil 17:00:17 INFO - building 'psutil._psutil_linux' extension 17:00:17 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 17:00:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:00:18 INFO - building 'psutil._psutil_posix' extension 17:00:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:00:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:00:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:18 INFO - Successfully installed psutil 17:00:18 INFO - Cleaning up... 17:00:18 INFO - Return code: 0 17:00:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:00:18 INFO - Using env: {'DISPLAY': ':0', 17:00:18 INFO - 'HOME': '/home/cltbld', 17:00:18 INFO - 'LANG': 'en_US.UTF-8', 17:00:18 INFO - 'LANGUAGE': 'en_US:en', 17:00:18 INFO - 'LOGNAME': 'cltbld', 17:00:18 INFO - 'MAIL': '/var/mail/cltbld', 17:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:18 INFO - 'MOZ_NO_REMOTE': '1', 17:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:18 INFO - 'NO_EM_RESTART': '1', 17:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:18 INFO - 'PWD': '/builds/slave/test', 17:00:18 INFO - 'SHELL': '/bin/bash', 17:00:18 INFO - 'SHLVL': '1', 17:00:18 INFO - 'TERM': 'linux', 17:00:18 INFO - 'TMOUT': '86400', 17:00:18 INFO - 'USER': 'cltbld', 17:00:18 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:18 INFO - '_': '/tools/buildbot/bin/python'} 17:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:00: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 17:00: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 17:00: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 17:00: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 17:00: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 17:00: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 17:00:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:00:20 INFO - Installing collected packages: mozsystemmonitor 17:00:20 INFO - Running setup.py install for mozsystemmonitor 17:00:20 INFO - Successfully installed mozsystemmonitor 17:00:20 INFO - Cleaning up... 17:00:20 INFO - Return code: 0 17:00:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:20 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:20 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 17:00:20 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 17:00:20 INFO - Using env: {'DISPLAY': ':0', 17:00:20 INFO - 'HOME': '/home/cltbld', 17:00:20 INFO - 'LANG': 'en_US.UTF-8', 17:00:20 INFO - 'LANGUAGE': 'en_US:en', 17:00:20 INFO - 'LOGNAME': 'cltbld', 17:00:20 INFO - 'MAIL': '/var/mail/cltbld', 17:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:20 INFO - 'MOZ_NO_REMOTE': '1', 17:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:20 INFO - 'NO_EM_RESTART': '1', 17:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:20 INFO - 'PWD': '/builds/slave/test', 17:00:20 INFO - 'SHELL': '/bin/bash', 17:00:20 INFO - 'SHLVL': '1', 17:00:20 INFO - 'TERM': 'linux', 17:00:20 INFO - 'TMOUT': '86400', 17:00:20 INFO - 'USER': 'cltbld', 17:00:20 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:20 INFO - '_': '/tools/buildbot/bin/python'} 17:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:20 INFO - Downloading/unpacking jsonschema==2.5.1 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:00:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:00:22 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 17:00:22 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 17:00:22 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 17:00:22 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 17:00:22 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 17:00:22 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 17:00:22 INFO - Downloading functools32-3.2.3-2.tar.gz 17:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:00:22 INFO - warning: no files found matching '*.txt' 17:00:22 INFO - no previously-included directories found matching 'build' 17:00:22 INFO - no previously-included directories found matching 'dist' 17:00:22 INFO - no previously-included directories found matching '.git*' 17:00:22 INFO - Installing collected packages: jsonschema, functools32 17:00:23 INFO - Running setup.py install for functools32 17:00:23 INFO - warning: no files found matching '*.txt' 17:00:23 INFO - no previously-included directories found matching 'build' 17:00:23 INFO - no previously-included directories found matching 'dist' 17:00:23 INFO - no previously-included directories found matching '.git*' 17:00:23 INFO - Successfully installed jsonschema functools32 17:00:23 INFO - Cleaning up... 17:00:23 INFO - Return code: 0 17:00:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:23 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:23 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 17:00:23 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 17:00:23 INFO - Using env: {'DISPLAY': ':0', 17:00:23 INFO - 'HOME': '/home/cltbld', 17:00:23 INFO - 'LANG': 'en_US.UTF-8', 17:00:23 INFO - 'LANGUAGE': 'en_US:en', 17:00:23 INFO - 'LOGNAME': 'cltbld', 17:00:23 INFO - 'MAIL': '/var/mail/cltbld', 17:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:23 INFO - 'MOZ_NO_REMOTE': '1', 17:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:23 INFO - 'NO_EM_RESTART': '1', 17:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:23 INFO - 'PWD': '/builds/slave/test', 17:00:23 INFO - 'SHELL': '/bin/bash', 17:00:23 INFO - 'SHLVL': '1', 17:00:23 INFO - 'TERM': 'linux', 17:00:23 INFO - 'TMOUT': '86400', 17:00:23 INFO - 'USER': 'cltbld', 17:00:23 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:23 INFO - '_': '/tools/buildbot/bin/python'} 17:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:23 INFO - Downloading/unpacking blobuploader==1.2.4 17:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:25 INFO - Downloading blobuploader-1.2.4.tar.gz 17:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:00:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:00:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 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 17:00:25 INFO - Downloading docopt-0.6.1.tar.gz 17:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:00:26 INFO - Installing collected packages: blobuploader, requests, docopt 17:00:26 INFO - Running setup.py install for blobuploader 17:00:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:00:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:00:26 INFO - Running setup.py install for requests 17:00:26 INFO - Running setup.py install for docopt 17:00:26 INFO - Successfully installed blobuploader requests docopt 17:00:26 INFO - Cleaning up... 17:00:26 INFO - Return code: 0 17:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:26 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')]} 17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 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')]} 17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:26 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 17:00:26 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 17:00:26 INFO - Using env: {'DISPLAY': ':0', 17:00:26 INFO - 'HOME': '/home/cltbld', 17:00:26 INFO - 'LANG': 'en_US.UTF-8', 17:00:26 INFO - 'LANGUAGE': 'en_US:en', 17:00:26 INFO - 'LOGNAME': 'cltbld', 17:00:26 INFO - 'MAIL': '/var/mail/cltbld', 17:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:26 INFO - 'MOZ_NO_REMOTE': '1', 17:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:26 INFO - 'NO_EM_RESTART': '1', 17:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:26 INFO - 'PWD': '/builds/slave/test', 17:00:26 INFO - 'SHELL': '/bin/bash', 17:00:26 INFO - 'SHLVL': '1', 17:00:26 INFO - 'TERM': 'linux', 17:00:26 INFO - 'TMOUT': '86400', 17:00:26 INFO - 'USER': 'cltbld', 17:00:26 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:26 INFO - '_': '/tools/buildbot/bin/python'} 17:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:00:26 INFO - Running setup.py (path:/tmp/pip-FzejHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:00:26 INFO - Running setup.py (path:/tmp/pip-v7mpZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:00:26 INFO - Running setup.py (path:/tmp/pip-e7oSQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:00:27 INFO - Running setup.py (path:/tmp/pip-cFAgst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:00:27 INFO - Running setup.py (path:/tmp/pip-1gcbon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:00:27 INFO - Running setup.py (path:/tmp/pip-CDnNUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:00:27 INFO - Running setup.py (path:/tmp/pip-Ew0GjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:00:27 INFO - Running setup.py (path:/tmp/pip-mmDIPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:00:27 INFO - Running setup.py (path:/tmp/pip-mnlFG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:00:27 INFO - Running setup.py (path:/tmp/pip-rhF4z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:00:27 INFO - Running setup.py (path:/tmp/pip-6SNorC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:00:27 INFO - Running setup.py (path:/tmp/pip-lAGG8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:00:27 INFO - Running setup.py (path:/tmp/pip-T8C8m_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:27 INFO - Running setup.py (path:/tmp/pip-PJbW7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:27 INFO - Running setup.py (path:/tmp/pip-3uUcq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:27 INFO - Running setup.py (path:/tmp/pip-McbuQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:28 INFO - Running setup.py (path:/tmp/pip-qRmHk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:00:28 INFO - Running setup.py install for manifestparser 17:00:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Running setup.py install for mozcrash 17:00:28 INFO - Running setup.py install for mozdebug 17:00:28 INFO - Running setup.py install for mozdevice 17:00:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Running setup.py install for mozfile 17:00:28 INFO - Running setup.py install for mozhttpd 17:00:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Running setup.py install for mozinfo 17:00:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Running setup.py install for mozInstall 17:00:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:00:28 INFO - Running setup.py install for mozleak 17:00:28 INFO - Running setup.py install for mozlog 17:00:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Running setup.py install for moznetwork 17:00:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Running setup.py install for mozprocess 17:00:29 INFO - Running setup.py install for mozprofile 17:00:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Running setup.py install for mozrunner 17:00:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Running setup.py install for mozscreenshot 17:00:29 INFO - Running setup.py install for moztest 17:00:29 INFO - Running setup.py install for mozversion 17:00:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:00:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:00:29 INFO - Cleaning up... 17:00:29 INFO - Return code: 0 17:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:29 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')]} 17:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:29 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')]} 17:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:29 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:29 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 17:00:29 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 17:00:29 INFO - Using env: {'DISPLAY': ':0', 17:00:29 INFO - 'HOME': '/home/cltbld', 17:00:29 INFO - 'LANG': 'en_US.UTF-8', 17:00:29 INFO - 'LANGUAGE': 'en_US:en', 17:00:29 INFO - 'LOGNAME': 'cltbld', 17:00:29 INFO - 'MAIL': '/var/mail/cltbld', 17:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:29 INFO - 'MOZ_NO_REMOTE': '1', 17:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:29 INFO - 'NO_EM_RESTART': '1', 17:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:29 INFO - 'PWD': '/builds/slave/test', 17:00:29 INFO - 'SHELL': '/bin/bash', 17:00:29 INFO - 'SHLVL': '1', 17:00:29 INFO - 'TERM': 'linux', 17:00:29 INFO - 'TMOUT': '86400', 17:00:29 INFO - 'USER': 'cltbld', 17:00:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:29 INFO - '_': '/tools/buildbot/bin/python'} 17:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:00:30 INFO - Running setup.py (path:/tmp/pip-QYR9VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:00:30 INFO - Running setup.py (path:/tmp/pip-h9iz8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:00:30 INFO - Running setup.py (path:/tmp/pip-W_HlWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:00:30 INFO - Running setup.py (path:/tmp/pip-qr5h2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:00:30 INFO - Running setup.py (path:/tmp/pip-XuLPRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:00:30 INFO - Running setup.py (path:/tmp/pip-CekOqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:00:30 INFO - Running setup.py (path:/tmp/pip-gIbioR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:00:30 INFO - Running setup.py (path:/tmp/pip-80AVed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:00:30 INFO - Running setup.py (path:/tmp/pip-0wi2GK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:00:30 INFO - Running setup.py (path:/tmp/pip-6FFaI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:00:30 INFO - Running setup.py (path:/tmp/pip-UYTN_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:00:30 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)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:00:30 INFO - Running setup.py (path:/tmp/pip-ajnXzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:00:30 INFO - Running setup.py (path:/tmp/pip-n2prYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:31 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)) 17:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:31 INFO - Running setup.py (path:/tmp/pip-6QVv6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:31 INFO - Running setup.py (path:/tmp/pip-okuAA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:31 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)) 17:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:31 INFO - Running setup.py (path:/tmp/pip-defO70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:31 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)) 17:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:31 INFO - Running setup.py (path:/tmp/pip-FCsltg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:31 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)) 17:00:31 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)) 17:00:31 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)) 17:00:31 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)) 17:00:31 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)) 17:00:31 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)) 17:00:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:00:31 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 17:00:31 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 17:00:31 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 17:00:31 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 17:00:31 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 17:00:31 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 17:00:33 INFO - Downloading blessings-1.6.tar.gz 17:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:00:33 INFO - Installing collected packages: blessings 17:00:33 INFO - Running setup.py install for blessings 17:00:33 INFO - Successfully installed blessings 17:00:33 INFO - Cleaning up... 17:00:33 INFO - Return code: 0 17:00:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:00:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:33 INFO - Reading from file tmpfile_stdout 17:00:33 INFO - Current package versions: 17:00:33 INFO - argparse == 1.2.1 17:00:33 INFO - blessings == 1.6 17:00:33 INFO - blobuploader == 1.2.4 17:00:33 INFO - docopt == 0.6.1 17:00:33 INFO - functools32 == 3.2.3-2 17:00:33 INFO - jsonschema == 2.5.1 17:00:33 INFO - manifestparser == 1.1 17:00:33 INFO - mozInstall == 1.12 17:00:33 INFO - mozcrash == 0.17 17:00:33 INFO - mozdebug == 0.1 17:00:33 INFO - mozdevice == 0.48 17:00:33 INFO - mozfile == 1.2 17:00:33 INFO - mozhttpd == 0.7 17:00:33 INFO - mozinfo == 0.9 17:00:33 INFO - mozleak == 0.1 17:00:33 INFO - mozlog == 3.2 17:00:33 INFO - moznetwork == 0.27 17:00:33 INFO - mozprocess == 0.23 17:00:33 INFO - mozprofile == 0.28 17:00:33 INFO - mozrunner == 6.12 17:00:33 INFO - mozscreenshot == 0.1 17:00:33 INFO - mozsystemmonitor == 0.3 17:00:33 INFO - moztest == 0.7 17:00:33 INFO - mozversion == 1.4 17:00:33 INFO - psutil == 3.1.1 17:00:33 INFO - requests == 1.2.3 17:00:33 INFO - wsgiref == 0.1.2 17:00:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:33 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:33 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 17:00:33 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 17:00:33 INFO - Using env: {'DISPLAY': ':0', 17:00:33 INFO - 'HOME': '/home/cltbld', 17:00:33 INFO - 'LANG': 'en_US.UTF-8', 17:00:33 INFO - 'LANGUAGE': 'en_US:en', 17:00:33 INFO - 'LOGNAME': 'cltbld', 17:00:33 INFO - 'MAIL': '/var/mail/cltbld', 17:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:33 INFO - 'MOZ_NO_REMOTE': '1', 17:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:33 INFO - 'NO_EM_RESTART': '1', 17:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:33 INFO - 'PWD': '/builds/slave/test', 17:00:33 INFO - 'SHELL': '/bin/bash', 17:00:33 INFO - 'SHLVL': '1', 17:00:33 INFO - 'TERM': 'linux', 17:00:33 INFO - 'TMOUT': '86400', 17:00:33 INFO - 'USER': 'cltbld', 17:00:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:33 INFO - '_': '/tools/buildbot/bin/python'} 17:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 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)) 17:00:33 INFO - Cleaning up... 17:00:33 INFO - Return code: 0 17:00:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:33 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 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:33 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 17:00:33 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 17:00:33 INFO - Using env: {'DISPLAY': ':0', 17:00:33 INFO - 'HOME': '/home/cltbld', 17:00:33 INFO - 'LANG': 'en_US.UTF-8', 17:00:33 INFO - 'LANGUAGE': 'en_US:en', 17:00:33 INFO - 'LOGNAME': 'cltbld', 17:00:33 INFO - 'MAIL': '/var/mail/cltbld', 17:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:33 INFO - 'MOZ_NO_REMOTE': '1', 17:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:33 INFO - 'NO_EM_RESTART': '1', 17:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:33 INFO - 'PWD': '/builds/slave/test', 17:00:33 INFO - 'SHELL': '/bin/bash', 17:00:33 INFO - 'SHLVL': '1', 17:00:33 INFO - 'TERM': 'linux', 17:00:33 INFO - 'TMOUT': '86400', 17:00:33 INFO - 'USER': 'cltbld', 17:00:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:33 INFO - '_': '/tools/buildbot/bin/python'} 17:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:00:34 INFO - Cleaning up... 17:00:34 INFO - Return code: 0 17:00:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4218481f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', '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 17:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:00:34 INFO - Using env: {'DISPLAY': ':0', 17:00:34 INFO - 'HOME': '/home/cltbld', 17:00:34 INFO - 'LANG': 'en_US.UTF-8', 17:00:34 INFO - 'LANGUAGE': 'en_US:en', 17:00:34 INFO - 'LOGNAME': 'cltbld', 17:00:34 INFO - 'MAIL': '/var/mail/cltbld', 17:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:34 INFO - 'MOZ_NO_REMOTE': '1', 17:00:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:34 INFO - 'NO_EM_RESTART': '1', 17:00:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:34 INFO - 'PWD': '/builds/slave/test', 17:00:34 INFO - 'SHELL': '/bin/bash', 17:00:34 INFO - 'SHLVL': '1', 17:00:34 INFO - 'TERM': 'linux', 17:00:34 INFO - 'TMOUT': '86400', 17:00:34 INFO - 'USER': 'cltbld', 17:00:34 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:34 INFO - '_': '/tools/buildbot/bin/python'} 17:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:34 INFO - Downloading/unpacking flake8 17:00:34 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 17:00:34 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 17:00:34 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 17:00:34 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 17:00:34 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 17:00:34 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 17:00:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:00:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:00:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 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 17:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:00:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:00:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:00:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:00:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:00:36 INFO - no previously-included directories found matching 'docs/_build' 17:00:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:00:36 INFO - Running setup.py install for pep8 17:00:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:00:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:00:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:00:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:00:37 INFO - no previously-included directories found matching 'docs/_build' 17:00:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:00:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:00:37 INFO - Cleaning up... 17:00:37 INFO - Return code: 0 17:00:37 INFO - Running post-action listener: _resource_record_post_action 17:00:37 INFO - Running post-action listener: _start_resource_monitoring 17:00:37 INFO - Starting resource monitoring. 17:00:37 INFO - ##### 17:00:37 INFO - ##### Finished create-virtualenv step (success) 17:00:37 INFO - ##### 17:00:37 INFO - ##### 17:00:37 INFO - ##### Running install step. 17:00:37 INFO - ##### 17:00:37 INFO - Running pre-action listener: _resource_record_pre_action 17:00:37 INFO - Running main action method: install 17:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:37 INFO - Reading from file tmpfile_stdout 17:00:37 INFO - Detecting whether we're running mozinstall >=1.0... 17:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:00:37 INFO - Reading from file tmpfile_stdout 17:00:37 INFO - Output received: 17:00:37 INFO - Usage: mozinstall [options] installer 17:00:37 INFO - Options: 17:00:37 INFO - -h, --help show this help message and exit 17:00:37 INFO - -d DEST, --destination=DEST 17:00:37 INFO - Directory to install application into. [default: 17:00:37 INFO - "/builds/slave/test"] 17:00:37 INFO - --app=APP Application being installed. [default: firefox] 17:00:37 INFO - mkdir: /builds/slave/test/build/application 17:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:00:50 INFO - Reading from file tmpfile_stdout 17:00:50 INFO - Output received: 17:00:50 INFO - /builds/slave/test/build/application/firefox/firefox 17:00:50 INFO - Running post-action listener: _resource_record_post_action 17:00:50 INFO - ##### 17:00:50 INFO - ##### Finished install step (success) 17:00:50 INFO - ##### 17:00:50 INFO - ##### 17:00:50 INFO - ##### Running run-tests step. 17:00:50 INFO - ##### 17:00:50 INFO - Running pre-action listener: _resource_record_pre_action 17:00:50 INFO - Running main action method: run_tests 17:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:00:50 INFO - Python 2.7.3 17:00:50 INFO - Return code: 0 17:00:50 INFO - grabbing minidump binary from tooltool 17:00: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')]} 17:00: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 0x12861c0>, '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 0x12870c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1287550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:00: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 17:00: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 17:00: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 17:00:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:00:50 INFO - Return code: 0 17:00:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:00:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:00:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:00:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:00:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:00:51 INFO - Return code: 0 17:00:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 17:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 17:00:51 INFO - Using env: {'DISPLAY': ':0', 17:00:51 INFO - 'HOME': '/home/cltbld', 17:00:51 INFO - 'LANG': 'en_US.UTF-8', 17:00:51 INFO - 'LANGUAGE': 'en_US:en', 17:00:51 INFO - 'LOGNAME': 'cltbld', 17:00:51 INFO - 'MAIL': '/var/mail/cltbld', 17:00:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:00:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:51 INFO - 'MOZ_NO_REMOTE': '1', 17:00:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:51 INFO - 'NO_EM_RESTART': '1', 17:00:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:51 INFO - 'PWD': '/builds/slave/test', 17:00:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:00:51 INFO - 'SHELL': '/bin/bash', 17:00:51 INFO - 'SHLVL': '1', 17:00:51 INFO - 'TERM': 'linux', 17:00:51 INFO - 'TMOUT': '86400', 17:00:51 INFO - 'USER': 'cltbld', 17:00:51 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342', 17:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:51 INFO - '_': '/tools/buildbot/bin/python'} 17:00:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 17:00:51 INFO - mozversion application_buildid: 20160803141223 17:00:51 INFO - mozversion application_changeset: 02304924791d467a6efe240d9012c02d91fe4703 17:00:51 INFO - mozversion application_display_name: Firefox 17:00:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:00:51 INFO - mozversion application_name: Firefox 17:00:51 INFO - mozversion application_remotingname: firefox 17:00:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:00:51 INFO - mozversion application_vendor: Mozilla 17:00:51 INFO - mozversion application_version: 49.0 17:00:51 INFO - mozversion platform_buildid: 20160803141223 17:00:51 INFO - mozversion platform_changeset: 02304924791d467a6efe240d9012c02d91fe4703 17:00:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:00:51 INFO - mozversion platform_version: 49.0 17:00:51 INFO - using testdate: 1470268851 17:00:51 INFO - actual date: 1470268851 17:00:51 INFO - starting webserver on 'localhost:51954' 17:00:51 INFO - SUITE-START | Running 2 tests 17:00:51 INFO - TEST-START | dromaeo_css 17:00:51 INFO - operating with platform_type : linux_ 17:00:51 INFO - Initialising browser for dromaeo_css test... 17:00:51 INFO - TEST-INFO | started process 19276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0vnlv0/profile http://localhost:51954/getInfo.html) 17:00:55 INFO - PROCESS | 19276 | __metrics Screen width/height:1600/1200 17:00:55 INFO - PROCESS | 19276 | colorDepth:24 17:00:55 INFO - PROCESS | 19276 | Browser inner width/height: 1024/697 17:00:55 INFO - PROCESS | 19276 | __metrics 17:00:57 INFO - PROCESS | 19276 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:00:57 ERROR - PROCESS | 19276 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:00:57 INFO - TEST-INFO | 19276: exit 0 17:00:57 INFO - Browser initialized. 17:00:57 INFO - Running cycle 1/1 for dromaeo_css test... 17:00:57 INFO - TEST-INFO | started process 19371 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0vnlv0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:01:09 INFO - PROCESS | 19371 | RSS: Main: 141115392 17:01:09 INFO - PROCESS | 19371 | 17:02:00 INFO - PROCESS | 19371 | 1470268920888 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] 17:04:09 INFO - PROCESS | 19371 | Cycle 1(1): loaded http://localhost:51954/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51954/tests/dromaeo/cssquery-ext.html) 17:04:10 INFO - PROCESS | 19371 | RSS: Main: 248299520 17:04:10 INFO - PROCESS | 19371 | 17:07:12 INFO - PROCESS | 19371 | Cycle 1(1): loaded http://localhost:51954/tests/dromaeo/cssquery-ext.html (next: http://localhost:51954/tests/dromaeo/cssquery-jquery.html) 17:07:13 INFO - PROCESS | 19371 | RSS: Main: 231743488 17:07:13 INFO - PROCESS | 19371 | 17:10:14 INFO - PROCESS | 19371 | Cycle 1(1): loaded http://localhost:51954/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51954/tests/dromaeo/cssquery-mootools.html) 17:10:15 INFO - PROCESS | 19371 | RSS: Main: 229339136 17:10:15 INFO - PROCESS | 19371 | 17:13:17 INFO - PROCESS | 19371 | Cycle 1(1): loaded http://localhost:51954/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51954/tests/dromaeo/cssquery-prototype.html) 17:13:17 INFO - PROCESS | 19371 | RSS: Main: 219410432 17:13:17 INFO - PROCESS | 19371 | 17:16:20 INFO - PROCESS | 19371 | Cycle 1(1): loaded http://localhost:51954/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51954/tests/dromaeo/cssquery-yui.html) 17:16:21 INFO - PROCESS | 19371 | RSS: Main: 209264640 17:16:21 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | Cycle 1(1): loaded http://localhost:51954/tests/dromaeo/cssquery-yui.html (next: http://localhost:51954/tests/dromaeo/cssquery-dojo.html) 17:19:23 INFO - PROCESS | 19371 | RSS: Main: 250609664 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | __start_tp_report 17:19:23 INFO - PROCESS | 19371 | _x_x_mozilla_page_load 17:19:23 INFO - PROCESS | 19371 | _x_x_mozilla_page_load_details 17:19:23 INFO - PROCESS | 19371 | |i|pagename|runs| 17:19:23 INFO - PROCESS | 19371 | |0;dojo.html;3434.038469228616;3450.982745086274;3453.7755045922004;3461.0655123116744;3482.6343233960433;5620.437956204382;5725.112607545831;5735.340435849878;5741.138829175626;5758.683272420015;5642.605017648766;5703.657780533171;5709.971450142754;5711.942880571193;5712.457316554921;5005.54950054495;5111.1566591512355;5163.690178589278;5176.922346164811;5205.115130427836;5078.13671675816;5164.92252616211;5199.142141546659;5212.609054320922;5217.634765566412;5112.514311140436;5163.044836705203;5172.353455818023;5173.818916337929;5227.634065615409;5262.894742105155;5267.288915996368;5272.894542109175;5280.81517146898;5312.760925758331;5014.548690617826;5016.598672106223;5068.721220332917;5174.560162386202;5182.689038657692;5510.448955104497;5523.1163013917985;5527.226188333637;5555.972220138914;5583.35791383992;6094.540970074305;6104.664243466607;6106.687898089199;6110.114033465167;6115.5413343998935;6062.42406971337;6117.113018612321;6139.324674285825;6154.815355539341;6164.7534098636;5687.573431992617;5729.942700572982;5738.1966524686995;5748.281464816898;5760.193572899798;6095.939040609625;6260.373962603794;6272.498200143978;6275.309715931244;6276.529260305495;5934.584579079423;6049.818505444844;6056.515478761688;6062.848428789227;6063.333033366326;6119.898632333103;6123.892747182667;6124.564025909317;6125.714741496629;6126.509879209652;5942.37605051472;5994.310654274727;6101.176341193971;6107.236595425572;6131.50947924165;5994.400559943971;6055.940210463152;6074.605150665193;6084.878302433926;6114.633122012605;4645.070985802853;4659.883502912454;4661.766911654471;4664.253719404879;4699.295105734099;4088.264112459726;4115.156392939441;4124.030852749602;4189.622933935961;4209.726367786084;5777.335606405233;5782.392848750905;5787.681677507778;5788.334341550775;5820.94179058204;5985.34161242263;6025.548083893726;6034.547408944347;6040.486558642565;6074.908876366858;5653.943460565424;5720.942790572124;5759.769609215668;5777.768889244383;5786.942130578725;5176.948230517647;5183.326167598264;5187.922181167285;5189.610779191576;5205.141151710001;6202.782902598387;6239.095331176954;6252.093446450239;6252.218472690914;6260.186175797117;5903;5989.940100598938;5996.220491336273;6068;6072.939270607414;6278.434940855345;6310.337414571404;6317.873642527215;6327.367263273636;6331.968340158454;5949.137375080676;5951.018082016419;5954.539324832605;5957.910631340446;5995.760169593167;5932.13983972313;5966.701664916824;5990.850228744316;6017.06735456005;6020.367861374492;5792.855178620568;5843.444872737083;5844.649321040752;5848.64908105515;5858.531317494505;5797.188393624981;5841.4450627190345;5851.941480585139;5856.531477481706;5859.882802343844;5718.885622287614;5790.449907258803;5841.737121829443;5853.678047707417;5881.7647294107755;5993.580449368503;6001.789937352171;6005;6033.75864965414;6034.547408944259;5650.152477128464;5821.738021788945;5824.854378640568;5829.504492118048;5847.415258474119 17:19:23 INFO - PROCESS | 19371 | |1;ext.html;614.6250787019917;648.6237981970457;662.9370209830066;664.860379320343;673.6261374936909;33566.49650255245;36452.45321320179;36551.63448365522;36613.816930915375;36617.26765464692;48174;48819.75590122053;48933.51066489334;52814.73592632041;52938.47061529384;9111.316651251165;9248.445093294391;9254.167124958753;9254.352195346295;9302.813943721121;8408.83182336356;8594.398392112555;9158.358914875962;10766.40784756838;10945.069669078139;11227.550897964113;11318.830217546743;11319.886801131966;11321.660350189466;11326.773464530705;11169.273997190157;11243.66269011931;11253.87461253868;11260.943695281512;11271.211015228937;11093.223474356799;11157.832632510519;11169.776604467865;11170.497327620276;11174.608888688937;45617.31574026392;45950.77024614855;45994.08011839745;46103.53896461026;46113.76943115296;3476.8261586920553;3633.872814451508;3736.5516138063335;3809.314323421783;4169.937450938239;32660;32833.67166328354;32894.34211315784;33014.50478242828;34174.48738268928;3838.5201849768778;3904.960950390488;3905.628965248297;3917.8041097945274;3982.1637456134245;386487;390082.0495897531;391564;391714;392622;38871;39882.40176397356;69952;70395.2960470399;70624.29375706177;42407.36388954168;42474;42584.361234581505;42656.14687706291;42668;17648.294068237126;17858.28566857337;17888.28446862137;17894.731579026324;17901.820981790017;13093.476260949456;14415.78376324356;14495.057821241586;14684.853151468562;14708.632284192981;9426.481543515172;9566.712998610054;9578.856317155249;9637.22902167825;9665.613375464902;3514.841832117561;3603.6036036036016;3654.926901461956;3657.6159503252306;3673.1735359544423;1098.8901109888948;1168.1414160591958;1173.1260211142583;1200.9219400739098;1201.627495476405;34005.31989360199;34267;34880;35001.82499087539;35476;1131.1120770195357;1139.0830381542937;1171.4494187731752;1230.4831970572382;1233.3031837012059;18343.266269349344;18363.265469381342;18460.538486537676;18555.72166417505;18664.34674786376;548101;548500;550432;550477;552095;66632.33367666262;66821;66935.33064669388;67226.66386668035;67260;1354.1536539662711;1403.0669604712753;1467.4717101843114;1481.4888863342346;1487.5686051045075;31676;35198.82400588083;35591.11022224362;35702.107447314025;36479;55173.72413137909;63980.3601963993;65630.34369656433;65725;70799.64600176967;465242;466127;469710;469712;474981;40586.79706601448;49126.26310605415;49860.50139498703;50587.49412505828;50630;52329.738351308;55322.72338638281;55841.44158558363;56153.43846561482;57186.14220786608;45817.77091114523;45856;46264;46395.304070438295;47313.763431182626;44963.55036449594;45446.77276613596;45737;46111.3083303744;46424.76787616176;45297.54702452933;45963.310550341106;46490.07019859651;46657.112963450025;47101;40206;41154.58845411507;41229.793851031754;41380.37929431121;41917.58082419137;29922;30135.370832837347;30259.243518912204;30264.243393915327;30733 17:19:23 INFO - PROCESS | 19371 | |2;jquery.html;414.5191577769788;423.1009105650493;428.8177524552066;435.2644031586619;441.72392254840724;18718.344857929977;19643.607127857453;20283.290084847034;20886.686699699516;20923;20000.0999955002;20135;20241.39275821724;20515.794842051575;20616.278430254944;5726.112452569859;5902.586818922677;5943.108533719934;5956.761729530814;5958.13607026983;5567.137093750475;5794.942050579482;5835.883282334351;6044.576879618428;6060.181875446824;6086.634801911878;6095.847603809896;6100.450959413652;6106.603070800406;6122.418370254819;6082.787102451415;6454.19322584677;6579.309172536862;6648.335166483362;6650.933490665104;5634.239377684001;5860.355360910296;5879.67661778602;5895.705214739246;5920.733566989453;14945.40218391263;15126.243687815675;15271.160086195256;15279.618009549738;22721.545569088692;2241.9663705044436;2289.3933107726466;2388.6536452214505;2511.8869650865754;2887.783416243769;11215.943920280346;11238.044766194884;11324.660260192208;11343.773124537463;13058.566172814992;1768.1601239411177;1801.8738688291826;1881.4261650196684;1927.4699457649258;1970.103602860695;86074;86599;87918.6040697969;87982.12017879868;88475.55762221212;11677.708057298636;11903.166778325607;12011.579594714143;12016.519339226508;12087.214331068453;16860.241289142017;17008.404705835237;17024.489265321812;17028.48914532566;17048.14759262032;8995.28037756978;9047.185753282236;9072.319576031825;9197.218236449977;9244.630214791467;9038.367314288069;9065.954670226607;9090.500022498694;9130.680426185052;9185.540722963826;5042.294078828967;5083.974580127076;5121.231815227746;5174.379074511083;5208.583313334924;1983.5239542509983;2087.645100332947;2101.747790265147;2110.504031552615;2122.915083396678;950.220818928472;982.94102353859;1022.5091955861228;1083.5828206140693;1131.7057565032985;19481.805181948283;20097.59804803896;20177.192912283637;20284.88433136162;20305;997.755549890267;1012.7518757904213;1027.1012863744224;1027.7841653252901;1035.2442716816765;10386.376817390981;11204.775904482029;11243.212975091828;11283.435828208556;11317.83023254652;399507;406854;409902;516551;518912;99809.00572982822;103202.48398757959;107056;107546.46226768816;107773;1079.8542196803487;1102.862142232221;1103.6302838549177;1111.7554138089608;1119.121489630636;17551.473455796346;17840.91079544594;17865.28538858431;17935.820641793936;18080.72878906791;42523.787381062895;42600.786996064824;42864.57135428606;43300.56699432965;43367;60102.39897601143;60977;61238.38761612327;61300.38699613125;62254.37745622667;8580.699675511327;8711.695090671796;8742.125787421208;8883.37816352849;8927.151920567536;9705;13008.479660813462;13073.019523535582;13118;13137.802932955821;11188.216824822184;11254.21220514556;11687.123465740273;11749.118816088998;12055.276683399024;11429.542818287508;11531.94234028858;11777.116716246488;11826.704332391759;11959.342236177094;11819;11863.169578129447;11866.703332416759;12026.75946481083;12114.454849531616;8972.910270897468;8988.730338089868;9053.77365565866;9192.586333615138;9312.48781317007;11579.710507237387;11673.474693638636;11769.17615766888;11822.290662560274;11947.701307467383 17:19:23 INFO - PROCESS | 19371 | |3;mootools.html;1397.7903314502817;1598.7921570195872;1617.6036870966616;1868.2993877296014;1965.0764140853794;9036.954815225923;9450.4802235877;9475.905240947597;9691.854622180674;9709.417434953908;9365.34442589019;9559;9570.904290957089;9577.712668619952;9586.664466743665;2455.337058994075;2486.1422809130822;2493.5262300163004;2493.9002439902383;2494.064725727852;2475.121331927167;2520.773130418262;2529.6711427514397;2529.7976161907095;2530.3547595363248;2491.028498885436;2505.2734706935057;2530.8734563271764;2535.936601584957;2536.0616571868436;2386.2602593196166;2404.519096180762;2418.2261676263515;2460.483298507316;2462.8522288662557;2427.077710470028;2430.732619411863;2438.7317395086525;2461.8153638477006;2471.2586224132688;9137.497437640926;9321.580528876217;9396.29527785412;9419.24646028323;9426.952865235698;5716.542676585863;5837.562182836305;5853.385394533598;5874.383189765056;5910.379410161914;3554.306910152521;3580.373434648952;3618.7466877318598;3653.6711695947492;3670.229251857113;1117.212365282474;1151.536208864845;1153.2561497833851;1166.906647468209;1184.7156682396162;13991.65020874476;14096.365663545164;14101.506447274398;14115.223662698547;14134.36395362211;4896.534829191221;4963.8014479421145;5021.046001259749;5043.193089105725;5066.189409694505;10856.620018299322;10877.945610271898;10893.618723344645;10896.40069796153;10931.890681093246;5753.942460575425;5757.222774925415;5762.510186634099;5763.7982670606325;5764;4713.717176969393;4803.303520989437;5257.316548848626;5263.052650522866;5281.815136470205;4372.606465418127;4456.532064133284;4506.549345065533;4551.2717965126085;4697.389339385926;1382.377929931535;1419.1626940105386;1435.167602790379;1440.863118003788;1447.486142419447;974.6004138303261;983.9212862970945;1006.572206812102;1007.0483393193362;1013.2400699475394;6036.849078772978;6156.815295541141;6191.566590338633;6264.874702505925;6271.686415679289;473.6992010073559;474.24358148752685;484.4259552430381;486.43573454792977;488.62131847817517;4145.813438395279;4163.250614889349;4183.393407955829;4201.18076974987;4226.619604235633;12017.098717596215;12379.195352302071;12491.75016499683;12492.81260781089;12506.81239781404;12317.260964342167;12346.197497162655;12347.506099756089;12352;12359;8654.35092368075;8971.910280897107;8979.50612716307;9072.04743501936;9198.080191980749;6024.698765061817;6132.877342453036;6246.437820596168;6301.842453938688;6404.967975160281;11127.165462590176;11303.830442543533;11377.715557110807;11453.02649274821;11732.648020559396;9909.801803963735;11641.59254426091;11674.241174323643;11805.586804461802;11837.053035756946;8359.916400835913;8607.913920860712;8616.698415555426;8857.51283679378;8872.33457490678;9173;9318.95340523293;9390.107939745712;9396.248300136109;9422.62309507612;8451.45065570736;8609.397342185986;8616.439931404439;8731.650733970824;8748.65005399777;8296.626651800803;8493.405461617627;8511.872321915052;8528.74413767588;8602.354823388396;7363.15323737777;7585.810354741176;7836.37309015266;7938.404619653434;7943.44395892282;5361.919571206513;5408.810691625774;5495.395506494203;5516.531094856982;5516.558675305886;5306.257124002565;5330.147176451749;5368.8389348319615;5387.434319396407;5478.068728316116 17:19:23 INFO - PROCESS | 19371 | |4;prototype.html;346.05872028083604;354.792446418845;354.85450965104314;361.5805665428104;364.6226155928614;5416.077292561138;5465.207544905987;5477.945220547794;5525.336959564847;5525.58558108142;5467.152591348347;5471.23402723619;5476.3702174249975;5494.972525137378;5498.230247765306;4236.682248831326;4290.806913688876;4308.418363520916;4309.762963037032;4328.805203765838;4247.617714405718;4277.807498662568;4280.550542193072;4284.443022407083;4289.270823959934;4029.9597004030024;4101.856435024774;4257.510386305568;4257.850975215868;4267;4240.830366785325;4258.74447533149;4303.827846886121;4304.7632380219075;4312.805923733439;3807.65731084201;3819.140693344003;3822.388417853158;3830.80845957701;3844.3272427325105;5031.811162144442;5102.6428150029515;5256.816011439581;5319.654222475527;5327.280817089721;4939.592216218361;4943.876403089916;4969;4969.155243608559;4978.676386034932;5694.316681998145;5702.344230413514;5727.940331038772;5730.68481233532;5799.623024503394;4201.642860356873;4234.195502854448;4238.618524332825;4251.3622956556455;4264.317709166549;5869.7945571904775;5898.852528686774;5921.555883308769;5933.614315069507;5942.910856337116;4389.18800021997;4393.462288199169;4393.692441529062;4403.251447253974;4406.405135306757;4371.606555410029;4411.360352748833;4417.4036505072045;4420.977895110504;4427.46870375557;4876.268559716071;4886.193778026657;4908.576512811316;4913.459519452857;4944.159492886218;5655.264815573949;5661.367464129117;5669.177969194444;5689.317281926195;5725.398833122462;1452.4262916148061;1535.1173075481643;1538.1847620761014;1539.722849887031;1542.8919975601825;999.240577161348;1001.3432653559653;1003.603576587244;1014.4876837197169;1020.6019652335596;4954.727489988085;4976.477469865685;4999.42506611737;5002.974985125051;5052.191649336089;5144.639875208774;5163.40620828602;5184.455632158644;5185.533302002837;5215.504527069922;5000.924986125211;5357.35711714589;5374.70439125844;5655.462731040627;5663.546916246691;3105.891293804706;3130.700759184913;3140.4975203967256;3141.15188898996;3141.607299087614;5819.359870414251;6138.7237574309265;6143.201383820075;6171.84570385744;6179.104029915637;6062.848428789315;6063.363346848606;6071.301800292935;6072.696365181723;6087.66517841523;6168.814935551941;6192;6211.254649442186;6221.9377806222265;6238.220222472191;5673.517750991212;5967.940320596738;5970.701464926823;5984.8803023940145;6008.497875531118;5843.707814609337;5845.912311315419;5860.912086318794;5922;5925.259342582177;5834.795782147605;6109.327973922954;6147.907781383194;6151.3541078186145;6226.501879849692;4232.682548808913;4273.551277115858;4305.4833419988545;4307.39696442492;4310.116426132698;5965.194698715792;6004.739004809039;6015.639061656315;6020.729067192075;6027.547933904887;5672.744726487235;5826.30084389859;5841.649501029951;5861.1501332307425;5868.882622347614;5816.604015036445;5836.649801011953;5852.502537284378;5869.853253668522;5882.823515294548;5483.643563168382;5778.104393818965;5780.8554786129;5781.653100813965;5811.680357580204;5844.181814546052;5858.506080949331;5859.882802344015;5878.676672783038;5881.441418024141;5486.780528778804;5752.309722833287;5761.91357129635;5790;5802.216700745345 17:19:23 INFO - PROCESS | 19371 | |5;yui.html;737.6791095873295;739.6301849075459;795.7745072588215;828.6975254032278;867.2844902955063;15898;16848.91575542122;17228.397006104788;17234;17278.740818887727;13542.864571354283;13553.525626603046;13573.389197486113;13574.185548867075;13675.111117777338;727.8544291141766;858.4806192253691;889.3241136736062;890.363390176024;896.6996056321141;880.8898887639045;886.1271647427279;888.4624801994797;889.0398369760675;890.7951171230621;931.4039015030386;933.9066093390675;934.3833070173687;934.6308208257734;934.9205317548017;753.8643044252033;895.6059333893098;920.4477313611847;921.7603423110006;923.5982347678781;675.7972608217515;679.3240725478181;681.655763839258;682.8395327098128;683.1051322767191;13727.245001524912;13832.792508112387;13857.722845543032;13905.235212063333;14006.159630422106;14732.116073035651;14878.776818347733;15252.161131137784;15308.311125999357;15319.846801532065;13330.266835324053;13371.26458044807;13513.594592162252;13583.864161358457;13687.931560342235;4647.372604698357;4673.696209746356;4694.57748802606;4701;4734.739589322586;73931;75201;75469;75561.62219188869;80735;11957.820632690517;12339.629811105502;12364.814527782091;12445.813312800316;12521.56174533887;21294.467638309165;21411.250606228707;21561.67657485139;21564.676529852066;22366.55266894653;7889.329407000355;7957.124716281204;8116.431849770577;8163.6514836891965;8231.259186673227;4603.746793926366;4612.60792832613;4650.790714417859;4693.225617773098;4781.258904869751;3568.3398571264397;4126.463559737215;4229.809658565372;4243.893902652459;4252.55348188442;640.286081019669;643.9130717353191;685.2291172431015;712.4905692429937;716.1978583985968;600.5351303141379;619.0868469008177;634.8730253949228;678.6674529480518;699.3530983839928;9046.231070358961;9145.451272923647;9290.16388525036;9298.526122841338;9543.61825526985;649.5810202419508;671.2515545167104;675.8918573028391;692.7713854428026;695.8677851208254;4235.364695295731;4469.245858435612;4621.792019359137;4640.86077417678;4663.953360466419;124156;125778.37110814387;127781;133423.33288333495;134892;106879.4656026715;109790;109912.4504377473;110636;110944;94093.5295323519;94628;94932.52533737425;96099.51950240343;97191;9083;9146;9204.539773011189;9240.168384845396;9270.771069934357;56736;57793.711031446255;58178;59798;61322.386776133455;81956.18043819486;84030.15969840223;85190.57404712937;85297.57351213205;87424;11365.829512557153;12036.75926481483;12180.75638487243;12201.572944946884;12302.507899684271;12571.18287311322;12903.354832258537;13055;13366.665833354244;13600.523981660594;11258.548433658103;11333.716657083638;11362.659120226406;11368.431578420881;11490.195686301879;12133.150679452707;12251.571195008131;12630.87369126297;12703.36483175819;12948.28784416866;11839.467223975114;11998.820017699567;12139.635810925687;12170.391480425766;12224.877751222373;10248.846267306146;10262.384256944608;10286.639967601099;10287.84568231462;10418.114460270961;9592.95203523978;9832.26258030665;9902.207823373968;9906.455144967096;9978.700638980843 17:19:23 INFO - PROCESS | 19371 | __end_tp_report 17:19:23 INFO - PROCESS | 19371 | __start_cc_report 17:19:23 INFO - PROCESS | 19371 | _x_x_mozilla_cycle_collect,851 17:19:23 INFO - PROCESS | 19371 | __end_cc_report 17:19:23 INFO - PROCESS | 19371 | __startTimestamp1470269963788__endTimestamp 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | ------- Summary: start ------- 17:19:23 INFO - PROCESS | 19371 | Number of tests: 6 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | [#0] dojo.html Cycles:165 Average:5639.78 Median:5829.50 stddev:618.58 (10.6%) stddev-sans-first:595.78 17:19:23 INFO - PROCESS | 19371 | Values: 3434.0 3451.0 3453.8 3461.1 3482.6 5620.4 5725.1 5735.3 5741.1 5758.7 5642.6 5703.7 5710.0 5711.9 5712.5 5005.5 5111.2 5163.7 5176.9 5205.1 5078.1 5164.9 5199.1 5212.6 5217.6 5112.5 5163.0 5172.4 5173.8 5227.6 5262.9 5267.3 5272.9 5280.8 5312.8 5014.5 5016.6 5068.7 5174.6 5182.7 5510.4 5523.1 5527.2 5556.0 5583.4 6094.5 6104.7 6106.7 6110.1 6115.5 6062.4 6117.1 6139.3 6154.8 6164.8 5687.6 5729.9 5738.2 5748.3 5760.2 6095.9 6260.4 6272.5 6275.3 6276.5 5934.6 6049.8 6056.5 6062.8 6063.3 6119.9 6123.9 6124.6 6125.7 6126.5 5942.4 5994.3 6101.2 6107.2 6131.5 5994.4 6055.9 6074.6 6084.9 6114.6 4645.1 4659.9 4661.8 4664.3 4699.3 4088.3 4115.2 4124.0 4189.6 4209.7 5777.3 5782.4 5787.7 5788.3 5820.9 5985.3 6025.5 6034.5 6040.5 6074.9 5653.9 5720.9 5759.8 5777.8 5786.9 5176.9 5183.3 5187.9 5189.6 5205.1 6202.8 6239.1 6252.1 6252.2 6260.2 5903.0 5989.9 5996.2 6068.0 6072.9 6278.4 6310.3 6317.9 6327.4 6332.0 5949.1 5951.0 5954.5 5957.9 5995.8 5932.1 5966.7 5990.9 6017.1 6020.4 5792.9 5843.4 5844.6 5848.6 5858.5 5797.2 5841.4 5851.9 5856.5 5859.9 5718.9 5790.4 5841.7 5853.7 5881.8 5993.6 6001.8 6005.0 6033.8 6034.5 5650.2 5821.7 5824.9 5829.5 5847.4 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | [#1] ext.html Cycles:180 Average:64391.05 Median:198302.08 stddev:125652.17 (63.4%) stddev-sans-first:125913.41 17:19:23 INFO - PROCESS | 19371 | Values: 614.6 648.6 662.9 664.9 673.6 33566.5 36452.5 36551.6 36613.8 36617.3 48174.0 48819.8 48933.5 52814.7 52938.5 9111.3 9248.4 9254.2 9254.4 9302.8 8408.8 8594.4 9158.4 10766.4 10945.1 11227.6 11318.8 11319.9 11321.7 11326.8 11169.3 11243.7 11253.9 11260.9 11271.2 11093.2 11157.8 11169.8 11170.5 11174.6 45617.3 45950.8 45994.1 46103.5 46113.8 3476.8 3633.9 3736.6 3809.3 4169.9 32660.0 32833.7 32894.3 33014.5 34174.5 3838.5 3905.0 3905.6 3917.8 3982.2 386487.0 390082.0 391564.0 391714.0 392622.0 38871.0 39882.4 69952.0 70395.3 70624.3 42407.4 42474.0 42584.4 42656.1 42668.0 17648.3 17858.3 17888.3 17894.7 17901.8 13093.5 14415.8 14495.1 14684.9 14708.6 9426.5 9566.7 9578.9 9637.2 9665.6 3514.8 3603.6 3654.9 3657.6 3673.2 1098.9 1168.1 1173.1 1200.9 1201.6 34005.3 34267.0 34880.0 35001.8 35476.0 1131.1 1139.1 1171.4 1230.5 1233.3 18343.3 18363.3 18460.5 18555.7 18664.3 548101.0 548500.0 550432.0 550477.0 552095.0 66632.3 66821.0 66935.3 67226.7 67260.0 1354.2 1403.1 1467.5 1481.5 1487.6 31676.0 35198.8 35591.1 35702.1 36479.0 55173.7 63980.4 65630.3 65725.0 70799.6 465242.0 466127.0 469710.0 469712.0 474981.0 40586.8 49126.3 49860.5 50587.5 50630.0 52329.7 55322.7 55841.4 56153.4 57186.1 45817.8 45856.0 46264.0 46395.3 47313.8 44963.6 45446.8 45737.0 46111.3 46424.8 45297.5 45963.3 46490.1 46657.1 47101.0 40206.0 41154.6 41229.8 41380.4 41917.6 29922.0 30135.4 30259.2 30264.2 30733.0 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | [#2] jquery.html Cycles:180 Average:28732.58 Median:11512.37 stddev:75521.24 (656.0%) stddev-sans-first:75703.16 17:19:23 INFO - PROCESS | 19371 | Values: 414.5 423.1 428.8 435.3 441.7 18718.3 19643.6 20283.3 20886.7 20923.0 20000.1 20135.0 20241.4 20515.8 20616.3 5726.1 5902.6 5943.1 5956.8 5958.1 5567.1 5794.9 5835.9 6044.6 6060.2 6086.6 6095.8 6100.5 6106.6 6122.4 6082.8 6454.2 6579.3 6648.3 6650.9 5634.2 5860.4 5879.7 5895.7 5920.7 14945.4 15126.2 15271.2 15279.6 22721.5 2242.0 2289.4 2388.7 2511.9 2887.8 11215.9 11238.0 11324.7 11343.8 13058.6 1768.2 1801.9 1881.4 1927.5 1970.1 86074.0 86599.0 87918.6 87982.1 88475.6 11677.7 11903.2 12011.6 12016.5 12087.2 16860.2 17008.4 17024.5 17028.5 17048.1 8995.3 9047.2 9072.3 9197.2 9244.6 9038.4 9066.0 9090.5 9130.7 9185.5 5042.3 5084.0 5121.2 5174.4 5208.6 1983.5 2087.6 2101.7 2110.5 2122.9 950.2 982.9 1022.5 1083.6 1131.7 19481.8 20097.6 20177.2 20284.9 20305.0 997.8 1012.8 1027.1 1027.8 1035.2 10386.4 11204.8 11243.2 11283.4 11317.8 399507.0 406854.0 409902.0 516551.0 518912.0 99809.0 103202.5 107056.0 107546.5 107773.0 1079.9 1102.9 1103.6 1111.8 1119.1 17551.5 17840.9 17865.3 17935.8 18080.7 42523.8 42600.8 42864.6 43300.6 43367.0 60102.4 60977.0 61238.4 61300.4 62254.4 8580.7 8711.7 8742.1 8883.4 8927.2 9705.0 13008.5 13073.0 13118.0 13137.8 11188.2 11254.2 11687.1 11749.1 12055.3 11429.5 11531.9 11777.1 11826.7 11959.3 11819.0 11863.2 11866.7 12026.8 12114.5 8972.9 8988.7 9053.8 9192.6 9312.5 11579.7 11673.5 11769.2 11822.3 11947.7 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | [#3] mootools.html Cycles:180 Average:6333.55 Median:2643.86 stddev:3731.87 (141.2%) stddev-sans-first:3723.90 17:19:23 INFO - PROCESS | 19371 | Values: 1397.8 1598.8 1617.6 1868.3 1965.1 9037.0 9450.5 9475.9 9691.9 9709.4 9365.3 9559.0 9570.9 9577.7 9586.7 2455.3 2486.1 2493.5 2493.9 2494.1 2475.1 2520.8 2529.7 2529.8 2530.4 2491.0 2505.3 2530.9 2535.9 2536.1 2386.3 2404.5 2418.2 2460.5 2462.9 2427.1 2430.7 2438.7 2461.8 2471.3 9137.5 9321.6 9396.3 9419.2 9427.0 5716.5 5837.6 5853.4 5874.4 5910.4 3554.3 3580.4 3618.7 3653.7 3670.2 1117.2 1151.5 1153.3 1166.9 1184.7 13991.7 14096.4 14101.5 14115.2 14134.4 4896.5 4963.8 5021.0 5043.2 5066.2 10856.6 10877.9 10893.6 10896.4 10931.9 5753.9 5757.2 5762.5 5763.8 5764.0 4713.7 4803.3 5257.3 5263.1 5281.8 4372.6 4456.5 4506.5 4551.3 4697.4 1382.4 1419.2 1435.2 1440.9 1447.5 974.6 983.9 1006.6 1007.0 1013.2 6036.8 6156.8 6191.6 6264.9 6271.7 473.7 474.2 484.4 486.4 488.6 4145.8 4163.3 4183.4 4201.2 4226.6 12017.1 12379.2 12491.8 12492.8 12506.8 12317.3 12346.2 12347.5 12352.0 12359.0 8654.4 8971.9 8979.5 9072.0 9198.1 6024.7 6132.9 6246.4 6301.8 6405.0 11127.2 11303.8 11377.7 11453.0 11732.6 9909.8 11641.6 11674.2 11805.6 11837.1 8359.9 8607.9 8616.7 8857.5 8872.3 9173.0 9319.0 9390.1 9396.2 9422.6 8451.5 8609.4 8616.4 8731.7 8748.7 8296.6 8493.4 8511.9 8528.7 8602.4 7363.2 7585.8 7836.4 7938.4 7943.4 5361.9 5408.8 5495.4 5516.5 5516.6 5306.3 5330.1 5368.8 5387.4 5478.1 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | [#4] prototype.html Cycles:180 Average:4844.59 Median:5366.03 stddev:1409.71 (26.3%) stddev-sans-first:1372.63 17:19:23 INFO - PROCESS | 19371 | Values: 346.1 354.8 354.9 361.6 364.6 5416.1 5465.2 5477.9 5525.3 5525.6 5467.2 5471.2 5476.4 5495.0 5498.2 4236.7 4290.8 4308.4 4309.8 4328.8 4247.6 4277.8 4280.6 4284.4 4289.3 4030.0 4101.9 4257.5 4257.9 4267.0 4240.8 4258.7 4303.8 4304.8 4312.8 3807.7 3819.1 3822.4 3830.8 3844.3 5031.8 5102.6 5256.8 5319.7 5327.3 4939.6 4943.9 4969.0 4969.2 4978.7 5694.3 5702.3 5727.9 5730.7 5799.6 4201.6 4234.2 4238.6 4251.4 4264.3 5869.8 5898.9 5921.6 5933.6 5942.9 4389.2 4393.5 4393.7 4403.3 4406.4 4371.6 4411.4 4417.4 4421.0 4427.5 4876.3 4886.2 4908.6 4913.5 4944.2 5655.3 5661.4 5669.2 5689.3 5725.4 1452.4 1535.1 1538.2 1539.7 1542.9 999.2 1001.3 1003.6 1014.5 1020.6 4954.7 4976.5 4999.4 5003.0 5052.2 5144.6 5163.4 5184.5 5185.5 5215.5 5000.9 5357.4 5374.7 5655.5 5663.5 3105.9 3130.7 3140.5 3141.2 3141.6 5819.4 6138.7 6143.2 6171.8 6179.1 6062.8 6063.4 6071.3 6072.7 6087.7 6168.8 6192.0 6211.3 6221.9 6238.2 5673.5 5967.9 5970.7 5984.9 6008.5 5843.7 5845.9 5860.9 5922.0 5925.3 5834.8 6109.3 6147.9 6151.4 6226.5 4232.7 4273.6 4305.5 4307.4 4310.1 5965.2 6004.7 6015.6 6020.7 6027.5 5672.7 5826.3 5841.6 5861.2 5868.9 5816.6 5836.6 5852.5 5869.9 5882.8 5483.6 5778.1 5780.9 5781.7 5811.7 5844.2 5858.5 5859.9 5878.7 5881.4 5486.8 5752.3 5761.9 5790.0 5802.2 17:19:23 INFO - PROCESS | 19371 | 17:19:23 INFO - PROCESS | 19371 | [#5] yui.html Cycles:180 Average:22045.00 Median:4693.90 stddev:33326.19 (710.0%) stddev-sans-first:33381.27 17:19:23 INFO - PROCESS | 19371 | Values: 737.7 739.6 795.8 828.7 867.3 15898.0 16848.9 17228.4 17234.0 17278.7 13542.9 13553.5 13573.4 13574.2 13675.1 727.9 858.5 889.3 890.4 896.7 880.9 886.1 888.5 889.0 890.8 931.4 933.9 934.4 934.6 934.9 753.9 895.6 920.4 921.8 923.6 675.8 679.3 681.7 682.8 683.1 13727.2 13832.8 13857.7 13905.2 14006.2 14732.1 14878.8 15252.2 15308.3 15319.8 13330.3 13371.3 13513.6 13583.9 13687.9 4647.4 4673.7 4694.6 4701.0 4734.7 73931.0 75201.0 75469.0 75561.6 80735.0 11957.8 12339.6 12364.8 12445.8 12521.6 21294.5 21411.3 21561.7 21564.7 22366.6 7889.3 7957.1 8116.4 8163.7 8231.3 4603.7 4612.6 4650.8 4693.2 4781.3 3568.3 4126.5 4229.8 4243.9 4252.6 640.3 643.9 685.2 712.5 716.2 600.5 619.1 634.9 678.7 699.4 9046.2 9145.5 9290.2 9298.5 9543.6 649.6 671.3 675.9 692.8 695.9 4235.4 4469.2 4621.8 4640.9 4664.0 124156.0 125778.4 127781.0 133423.3 134892.0 106879.5 109790.0 109912.5 110636.0 110944.0 94093.5 94628.0 94932.5 96099.5 97191.0 9083.0 9146.0 9204.5 9240.2 9270.8 56736.0 57793.7 58178.0 59798.0 61322.4 81956.2 84030.2 85190.6 85297.6 87424.0 11365.8 12036.8 12180.8 12201.6 12302.5 12571.2 12903.4 13055.0 13366.7 13600.5 11258.5 11333.7 11362.7 11368.4 11490.2 12133.2 12251.6 12630.9 12703.4 12948.3 11839.5 11998.8 12139.6 12170.4 12224.9 10248.8 10262.4 10286.6 10287.8 10418.1 9593.0 9832.3 9902.2 9906.5 9978.7 17:19:23 INFO - PROCESS | 19371 | -------- Summary: end -------- 17:19:23 INFO - PROCESS | 19371 | 17:19:24 INFO - TEST-INFO | 19371: exit 0 17:19:24 INFO - TEST-OK | dromaeo_css | took 1112718ms 17:19:24 INFO - TEST-START | kraken 17:19:24 INFO - operating with platform_type : linux_ 17:19:24 INFO - Initialising browser for kraken test... 17:19:24 INFO - TEST-INFO | started process 19570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxA3NOp/profile http://localhost:51954/getInfo.html) 17:19:28 INFO - PROCESS | 19570 | __metrics Screen width/height:1600/1200 17:19:28 INFO - PROCESS | 19570 | colorDepth:24 17:19:28 INFO - PROCESS | 19570 | Browser inner width/height: 1024/697 17:19:28 INFO - PROCESS | 19570 | __metrics 17:19:29 INFO - PROCESS | 19570 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:19:29 ERROR - PROCESS | 19570 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:19:30 INFO - TEST-INFO | 19570: exit 0 17:19:30 INFO - Browser initialized. 17:19:30 INFO - Running cycle 1/1 for kraken test... 17:19:30 INFO - TEST-INFO | started process 19663 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxA3NOp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:42 INFO - PROCESS | 19663 | RSS: Main: 144408576 17:19:42 INFO - PROCESS | 19663 | 17:19:44 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51954/tests/kraken/driver.html?testName=audio-beat-detection) 17:19:45 INFO - PROCESS | 19663 | RSS: Main: 195420160 17:19:45 INFO - PROCESS | 19663 | 17:19:48 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51954/tests/kraken/driver.html?testName=audio-dft) 17:19:48 INFO - PROCESS | 19663 | RSS: Main: 191856640 17:19:48 INFO - PROCESS | 19663 | 17:19:51 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51954/tests/kraken/driver.html?testName=audio-fft) 17:19:52 INFO - PROCESS | 19663 | RSS: Main: 278863872 17:19:52 INFO - PROCESS | 19663 | 17:19:54 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51954/tests/kraken/driver.html?testName=audio-oscillator) 17:19:54 INFO - PROCESS | 19663 | RSS: Main: 187367424 17:19:54 INFO - PROCESS | 19663 | 17:19:57 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51954/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:19:57 INFO - PROCESS | 19663 | RSS: Main: 187789312 17:19:57 INFO - PROCESS | 19663 | 17:20:00 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51954/tests/kraken/driver.html?testName=imaging-darkroom) 17:20:01 INFO - PROCESS | 19663 | RSS: Main: 222937088 17:20:01 INFO - PROCESS | 19663 | 17:20:04 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51954/tests/kraken/driver.html?testName=imaging-desaturate) 17:20:05 INFO - PROCESS | 19663 | RSS: Main: 223981568 17:20:05 INFO - PROCESS | 19663 | 17:20:07 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51954/tests/kraken/driver.html?testName=json-parse-financial) 17:20:08 INFO - PROCESS | 19663 | RSS: Main: 222744576 17:20:08 INFO - PROCESS | 19663 | 17:20:09 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51954/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:20:10 INFO - PROCESS | 19663 | RSS: Main: 201945088 17:20:10 INFO - PROCESS | 19663 | 17:20:12 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:20:12 INFO - PROCESS | 19663 | RSS: Main: 202825728 17:20:12 INFO - PROCESS | 19663 | 17:20:14 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:20:15 INFO - PROCESS | 19663 | RSS: Main: 206270464 17:20:15 INFO - PROCESS | 19663 | 17:20:17 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:20:18 INFO - PROCESS | 19663 | RSS: Main: 211496960 17:20:18 INFO - PROCESS | 19663 | 17:20:21 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:20:21 INFO - PROCESS | 19663 | RSS: Main: 213217280 17:20:21 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | Cycle 1(1): loaded http://localhost:51954/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51954/tests/kraken/driver.html?testName=ai-astar) 17:20:23 INFO - PROCESS | 19663 | RSS: Main: 255479808 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | __start_tp_report 17:20:23 INFO - PROCESS | 19663 | _x_x_mozilla_page_load 17:20:23 INFO - PROCESS | 19663 | _x_x_mozilla_page_load_details 17:20:23 INFO - PROCESS | 19663 | |i|pagename|runs| 17:20:23 INFO - PROCESS | 19663 | |0;ai-astar;110;104;110;111;110;108;109;111;109;110 17:20:23 INFO - PROCESS | 19663 | |1;audio-beat-detection;155;130;126;183;124;132;149;124;181;124 17:20:23 INFO - PROCESS | 19663 | |2;audio-dft;161;160;160;162;160;163;159;160;160;159 17:20:23 INFO - PROCESS | 19663 | |3;audio-fft;76;76;76;101;75;76;75;76;98;76 17:20:23 INFO - PROCESS | 19663 | |4;audio-oscillator;84;84;121;83;86;85;119;84;84;120 17:20:23 INFO - PROCESS | 19663 | |5;imaging-gaussian-blur;117;117;116;118;117;117;117;116;117;116 17:20:23 INFO - PROCESS | 19663 | |6;imaging-darkroom;146;145;145;145;145;145;144;145;145;145 17:20:23 INFO - PROCESS | 19663 | |7;imaging-desaturate;95;94;95;94;96;95;94;95;94;95 17:20:23 INFO - PROCESS | 19663 | |8;json-parse-financial;50;49;49;50;49;50;51;51;49;50 17:20:23 INFO - PROCESS | 19663 | |9;json-stringify-tinderbox;59;59;59;59;59;59;58;59;60;60 17:20:23 INFO - PROCESS | 19663 | |10;stanford-crypto-aes;76;76;77;76;76;82;77;78;112;76 17:20:23 INFO - PROCESS | 19663 | |11;stanford-crypto-ccm;124;117;116;116;117;116;115;117;121;118 17:20:23 INFO - PROCESS | 19663 | |12;stanford-crypto-pbkdf2;152;161;153;152;151;152;159;154;156;153 17:20:23 INFO - PROCESS | 19663 | |13;stanford-crypto-sha256-iterative;61;62;61;60;61;62;62;61;65;63 17:20:23 INFO - PROCESS | 19663 | __end_tp_report 17:20:23 INFO - PROCESS | 19663 | __start_cc_report 17:20:23 INFO - PROCESS | 19663 | _x_x_mozilla_cycle_collect,1536 17:20:23 INFO - PROCESS | 19663 | __end_cc_report 17:20:23 INFO - PROCESS | 19663 | __startTimestamp1470270023677__endTimestamp 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | ------- Summary: start ------- 17:20:23 INFO - PROCESS | 19663 | Number of tests: 14 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:2.04 (1.9%) stddev-sans-first:2.15 17:20:23 INFO - PROCESS | 19663 | Values: 110.0 104.0 110.0 111.0 110.0 108.0 109.0 111.0 109.0 110.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:140.50 stddev:23.30 (16.6%) stddev-sans-first:24.29 17:20:23 INFO - PROCESS | 19663 | Values: 155.0 130.0 126.0 183.0 124.0 132.0 149.0 124.0 181.0 124.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:1.26 (0.8%) stddev-sans-first:1.32 17:20:23 INFO - PROCESS | 19663 | Values: 161.0 160.0 160.0 162.0 160.0 163.0 159.0 160.0 160.0 159.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#3] audio-fft Cycles:10 Average:80.50 Median:76.00 stddev:10.05 (13.2%) stddev-sans-first:10.52 17:20:23 INFO - PROCESS | 19663 | Values: 76.0 76.0 76.0 101.0 75.0 76.0 75.0 76.0 98.0 76.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:84.00 stddev:17.28 (20.6%) stddev-sans-first:17.86 17:20:23 INFO - PROCESS | 19663 | Values: 84.0 84.0 121.0 83.0 86.0 85.0 119.0 84.0 84.0 120.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 17:20:23 INFO - PROCESS | 19663 | Values: 117.0 117.0 116.0 118.0 117.0 117.0 117.0 116.0 117.0 116.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 17:20:23 INFO - PROCESS | 19663 | Values: 146.0 145.0 145.0 145.0 145.0 145.0 144.0 145.0 145.0 145.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 17:20:23 INFO - PROCESS | 19663 | Values: 95.0 94.0 95.0 94.0 96.0 95.0 94.0 95.0 94.0 95.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.00 stddev:0.79 (1.6%) stddev-sans-first:0.83 17:20:23 INFO - PROCESS | 19663 | Values: 50.0 49.0 49.0 50.0 49.0 50.0 51.0 51.0 49.0 50.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 17:20:23 INFO - PROCESS | 19663 | Values: 59.0 59.0 59.0 59.0 59.0 59.0 58.0 59.0 60.0 60.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:76.50 stddev:11.19 (14.6%) stddev-sans-first:11.74 17:20:23 INFO - PROCESS | 19663 | Values: 76.0 76.0 77.0 76.0 76.0 82.0 77.0 78.0 112.0 76.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#11] stanford-crypto-ccm Cycles:10 Average:117.70 Median:117.00 stddev:2.75 (2.4%) stddev-sans-first:1.73 17:20:23 INFO - PROCESS | 19663 | Values: 124.0 117.0 116.0 116.0 117.0 116.0 115.0 117.0 121.0 118.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:153.50 stddev:3.33 (2.2%) stddev-sans-first:3.43 17:20:23 INFO - PROCESS | 19663 | Values: 152.0 161.0 153.0 152.0 151.0 152.0 159.0 154.0 156.0 153.0 17:20:23 INFO - PROCESS | 19663 | 17:20:23 INFO - PROCESS | 19663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.40 (2.3%) stddev-sans-first:1.45 17:20:23 INFO - PROCESS | 19663 | Values: 61.0 62.0 61.0 60.0 61.0 62.0 62.0 61.0 65.0 63.0 17:20:23 INFO - PROCESS | 19663 | -------- Summary: end -------- 17:20:23 INFO - PROCESS | 19663 | 17:20:24 INFO - TEST-INFO | 19663: exit 0 17:20:24 INFO - TEST-OK | kraken | took 60129ms 17:20:24 INFO - SUITE-END | took 1172s 17:20:24 INFO - Completed test suite (00:19:33) 17:20:24 INFO - javascript benchmark 17:20:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3434.038469228616, 3450.982745086274, 3453.7755045922004, 3461.0655123116744, 3482.6343233960433, 5620.437956204382, 5725.112607545831, 5735.340435849878, 5741.138829175626, 5758.683272420015, 5642.605017648766, 5703.657780533171, 5709.971450142754, 5711.942880571193, 5712.457316554921, 5005.54950054495, 5111.1566591512355, 5163.690178589278, 5176.922346164811, 5205.115130427836, 5078.13671675816, 5164.92252616211, 5199.142141546659, 5212.609054320922, 5217.634765566412, 5112.514311140436, 5163.044836705203, 5172.353455818023, 5173.818916337929, 5227.634065615409, 5262.894742105155, 5267.288915996368, 5272.894542109175, 5280.81517146898, 5312.760925758331, 5014.548690617826, 5016.598672106223, 5068.721220332917, 5174.560162386202, 5182.689038657692, 5510.448955104497, 5523.1163013917985, 5527.226188333637, 5555.972220138914, 5583.35791383992, 6094.540970074305, 6104.664243466607, 6106.687898089199, 6110.114033465167, 6115.5413343998935, 6062.42406971337, 6117.113018612321, 6139.324674285825, 6154.815355539341, 6164.7534098636, 5687.573431992617, 5729.942700572982, 5738.1966524686995, 5748.281464816898, 5760.193572899798, 6095.939040609625, 6260.373962603794, 6272.498200143978, 6275.309715931244, 6276.529260305495, 5934.584579079423, 6049.818505444844, 6056.515478761688, 6062.848428789227, 6063.333033366326, 6119.898632333103, 6123.892747182667, 6124.564025909317, 6125.714741496629, 6126.509879209652, 5942.37605051472, 5994.310654274727, 6101.176341193971, 6107.236595425572, 6131.50947924165, 5994.400559943971, 6055.940210463152, 6074.605150665193, 6084.878302433926, 6114.633122012605, 4645.070985802853, 4659.883502912454, 4661.766911654471, 4664.253719404879, 4699.295105734099, 4088.264112459726, 4115.156392939441, 4124.030852749602, 4189.622933935961, 4209.726367786084, 5777.335606405233, 5782.392848750905, 5787.681677507778, 5788.334341550775, 5820.94179058204, 5985.34161242263, 6025.548083893726, 6034.547408944347, 6040.486558642565, 6074.908876366858, 5653.943460565424, 5720.942790572124, 5759.769609215668, 5777.768889244383, 5786.942130578725, 5176.948230517647, 5183.326167598264, 5187.922181167285, 5189.610779191576, 5205.141151710001, 6202.782902598387, 6239.095331176954, 6252.093446450239, 6252.218472690914, 6260.186175797117, 5903.0, 5989.940100598938, 5996.220491336273, 6068.0, 6072.939270607414, 6278.434940855345, 6310.337414571404, 6317.873642527215, 6327.367263273636, 6331.968340158454, 5949.137375080676, 5951.018082016419, 5954.539324832605, 5957.910631340446, 5995.760169593167, 5932.13983972313, 5966.701664916824, 5990.850228744316, 6017.06735456005, 6020.367861374492, 5792.855178620568, 5843.444872737083, 5844.649321040752, 5848.64908105515, 5858.531317494505, 5797.188393624981, 5841.4450627190345, 5851.941480585139, 5856.531477481706, 5859.882802343844, 5718.885622287614, 5790.449907258803, 5841.737121829443, 5853.678047707417, 5881.7647294107755, 5993.580449368503, 6001.789937352171, 6005.0, 6033.75864965414, 6034.547408944259, 5650.152477128464, 5821.738021788945, 5824.854378640568, 5829.504492118048, 5847.415258474119], "value": 5600.238063497385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.6250787019917, 648.6237981970457, 662.9370209830066, 664.860379320343, 673.6261374936909, 33566.49650255245, 36452.45321320179, 36551.63448365522, 36613.816930915375, 36617.26765464692, 48174.0, 48819.75590122053, 48933.51066489334, 52814.73592632041, 52938.47061529384, 9111.316651251165, 9248.445093294391, 9254.167124958753, 9254.352195346295, 9302.813943721121, 8408.83182336356, 8594.398392112555, 9158.358914875962, 10766.40784756838, 10945.069669078139, 11227.550897964113, 11318.830217546743, 11319.886801131966, 11321.660350189466, 11326.773464530705, 11169.273997190157, 11243.66269011931, 11253.87461253868, 11260.943695281512, 11271.211015228937, 11093.223474356799, 11157.832632510519, 11169.776604467865, 11170.497327620276, 11174.608888688937, 45617.31574026392, 45950.77024614855, 45994.08011839745, 46103.53896461026, 46113.76943115296, 3476.8261586920553, 3633.872814451508, 3736.5516138063335, 3809.314323421783, 4169.937450938239, 32660.0, 32833.67166328354, 32894.34211315784, 33014.50478242828, 34174.48738268928, 3838.5201849768778, 3904.960950390488, 3905.628965248297, 3917.8041097945274, 3982.1637456134245, 386487.0, 390082.0495897531, 391564.0, 391714.0, 392622.0, 38871.0, 39882.40176397356, 69952.0, 70395.2960470399, 70624.29375706177, 42407.36388954168, 42474.0, 42584.361234581505, 42656.14687706291, 42668.0, 17648.294068237126, 17858.28566857337, 17888.28446862137, 17894.731579026324, 17901.820981790017, 13093.476260949456, 14415.78376324356, 14495.057821241586, 14684.853151468562, 14708.632284192981, 9426.481543515172, 9566.712998610054, 9578.856317155249, 9637.22902167825, 9665.613375464902, 3514.841832117561, 3603.6036036036016, 3654.926901461956, 3657.6159503252306, 3673.1735359544423, 1098.8901109888948, 1168.1414160591958, 1173.1260211142583, 1200.9219400739098, 1201.627495476405, 34005.31989360199, 34267.0, 34880.0, 35001.82499087539, 35476.0, 1131.1120770195357, 1139.0830381542937, 1171.4494187731752, 1230.4831970572382, 1233.3031837012059, 18343.266269349344, 18363.265469381342, 18460.538486537676, 18555.72166417505, 18664.34674786376, 548101.0, 548500.0, 550432.0, 550477.0, 552095.0, 66632.33367666262, 66821.0, 66935.33064669388, 67226.66386668035, 67260.0, 1354.1536539662711, 1403.0669604712753, 1467.4717101843114, 1481.4888863342346, 1487.5686051045075, 31676.0, 35198.82400588083, 35591.11022224362, 35702.107447314025, 36479.0, 55173.72413137909, 63980.3601963993, 65630.34369656433, 65725.0, 70799.64600176967, 465242.0, 466127.0, 469710.0, 469712.0, 474981.0, 40586.79706601448, 49126.26310605415, 49860.50139498703, 50587.49412505828, 50630.0, 52329.738351308, 55322.72338638281, 55841.44158558363, 56153.43846561482, 57186.14220786608, 45817.77091114523, 45856.0, 46264.0, 46395.304070438295, 47313.763431182626, 44963.55036449594, 45446.77276613596, 45737.0, 46111.3083303744, 46424.76787616176, 45297.54702452933, 45963.310550341106, 46490.07019859651, 46657.112963450025, 47101.0, 40206.0, 41154.58845411507, 41229.793851031754, 41380.37929431121, 41917.58082419137, 29922.0, 30135.370832837347, 30259.243518912204, 30264.243393915327, 30733.0], "value": 21079.942153356107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.5191577769788, 423.1009105650493, 428.8177524552066, 435.2644031586619, 441.72392254840724, 18718.344857929977, 19643.607127857453, 20283.290084847034, 20886.686699699516, 20923.0, 20000.0999955002, 20135.0, 20241.39275821724, 20515.794842051575, 20616.278430254944, 5726.112452569859, 5902.586818922677, 5943.108533719934, 5956.761729530814, 5958.13607026983, 5567.137093750475, 5794.942050579482, 5835.883282334351, 6044.576879618428, 6060.181875446824, 6086.634801911878, 6095.847603809896, 6100.450959413652, 6106.603070800406, 6122.418370254819, 6082.787102451415, 6454.19322584677, 6579.309172536862, 6648.335166483362, 6650.933490665104, 5634.239377684001, 5860.355360910296, 5879.67661778602, 5895.705214739246, 5920.733566989453, 14945.40218391263, 15126.243687815675, 15271.160086195256, 15279.618009549738, 22721.545569088692, 2241.9663705044436, 2289.3933107726466, 2388.6536452214505, 2511.8869650865754, 2887.783416243769, 11215.943920280346, 11238.044766194884, 11324.660260192208, 11343.773124537463, 13058.566172814992, 1768.1601239411177, 1801.8738688291826, 1881.4261650196684, 1927.4699457649258, 1970.103602860695, 86074.0, 86599.0, 87918.6040697969, 87982.12017879868, 88475.55762221212, 11677.708057298636, 11903.166778325607, 12011.579594714143, 12016.519339226508, 12087.214331068453, 16860.241289142017, 17008.404705835237, 17024.489265321812, 17028.48914532566, 17048.14759262032, 8995.28037756978, 9047.185753282236, 9072.319576031825, 9197.218236449977, 9244.630214791467, 9038.367314288069, 9065.954670226607, 9090.500022498694, 9130.680426185052, 9185.540722963826, 5042.294078828967, 5083.974580127076, 5121.231815227746, 5174.379074511083, 5208.583313334924, 1983.5239542509983, 2087.645100332947, 2101.747790265147, 2110.504031552615, 2122.915083396678, 950.220818928472, 982.94102353859, 1022.5091955861228, 1083.5828206140693, 1131.7057565032985, 19481.805181948283, 20097.59804803896, 20177.192912283637, 20284.88433136162, 20305.0, 997.755549890267, 1012.7518757904213, 1027.1012863744224, 1027.7841653252901, 1035.2442716816765, 10386.376817390981, 11204.775904482029, 11243.212975091828, 11283.435828208556, 11317.83023254652, 399507.0, 406854.0, 409902.0, 516551.0, 518912.0, 99809.00572982822, 103202.48398757959, 107056.0, 107546.46226768816, 107773.0, 1079.8542196803487, 1102.862142232221, 1103.6302838549177, 1111.7554138089608, 1119.121489630636, 17551.473455796346, 17840.91079544594, 17865.28538858431, 17935.820641793936, 18080.72878906791, 42523.787381062895, 42600.786996064824, 42864.57135428606, 43300.56699432965, 43367.0, 60102.39897601143, 60977.0, 61238.38761612327, 61300.38699613125, 62254.37745622667, 8580.699675511327, 8711.695090671796, 8742.125787421208, 8883.37816352849, 8927.151920567536, 9705.0, 13008.479660813462, 13073.019523535582, 13118.0, 13137.802932955821, 11188.216824822184, 11254.21220514556, 11687.123465740273, 11749.118816088998, 12055.276683399024, 11429.542818287508, 11531.94234028858, 11777.116716246488, 11826.704332391759, 11959.342236177094, 11819.0, 11863.169578129447, 11866.703332416759, 12026.75946481083, 12114.454849531616, 8972.910270897468, 8988.730338089868, 9053.77365565866, 9192.586333615138, 9312.48781317007, 11579.710507237387, 11673.474693638636, 11769.17615766888, 11822.290662560274, 11947.701307467383], "value": 9579.909644709564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.7903314502817, 1598.7921570195872, 1617.6036870966616, 1868.2993877296014, 1965.0764140853794, 9036.954815225923, 9450.4802235877, 9475.905240947597, 9691.854622180674, 9709.417434953908, 9365.34442589019, 9559.0, 9570.904290957089, 9577.712668619952, 9586.664466743665, 2455.337058994075, 2486.1422809130822, 2493.5262300163004, 2493.9002439902383, 2494.064725727852, 2475.121331927167, 2520.773130418262, 2529.6711427514397, 2529.7976161907095, 2530.3547595363248, 2491.028498885436, 2505.2734706935057, 2530.8734563271764, 2535.936601584957, 2536.0616571868436, 2386.2602593196166, 2404.519096180762, 2418.2261676263515, 2460.483298507316, 2462.8522288662557, 2427.077710470028, 2430.732619411863, 2438.7317395086525, 2461.8153638477006, 2471.2586224132688, 9137.497437640926, 9321.580528876217, 9396.29527785412, 9419.24646028323, 9426.952865235698, 5716.542676585863, 5837.562182836305, 5853.385394533598, 5874.383189765056, 5910.379410161914, 3554.306910152521, 3580.373434648952, 3618.7466877318598, 3653.6711695947492, 3670.229251857113, 1117.212365282474, 1151.536208864845, 1153.2561497833851, 1166.906647468209, 1184.7156682396162, 13991.65020874476, 14096.365663545164, 14101.506447274398, 14115.223662698547, 14134.36395362211, 4896.534829191221, 4963.8014479421145, 5021.046001259749, 5043.193089105725, 5066.189409694505, 10856.620018299322, 10877.945610271898, 10893.618723344645, 10896.40069796153, 10931.890681093246, 5753.942460575425, 5757.222774925415, 5762.510186634099, 5763.7982670606325, 5764.0, 4713.717176969393, 4803.303520989437, 5257.316548848626, 5263.052650522866, 5281.815136470205, 4372.606465418127, 4456.532064133284, 4506.549345065533, 4551.2717965126085, 4697.389339385926, 1382.377929931535, 1419.1626940105386, 1435.167602790379, 1440.863118003788, 1447.486142419447, 974.6004138303261, 983.9212862970945, 1006.572206812102, 1007.0483393193362, 1013.2400699475394, 6036.849078772978, 6156.815295541141, 6191.566590338633, 6264.874702505925, 6271.686415679289, 473.6992010073559, 474.24358148752685, 484.4259552430381, 486.43573454792977, 488.62131847817517, 4145.813438395279, 4163.250614889349, 4183.393407955829, 4201.18076974987, 4226.619604235633, 12017.098717596215, 12379.195352302071, 12491.75016499683, 12492.81260781089, 12506.81239781404, 12317.260964342167, 12346.197497162655, 12347.506099756089, 12352.0, 12359.0, 8654.35092368075, 8971.910280897107, 8979.50612716307, 9072.04743501936, 9198.080191980749, 6024.698765061817, 6132.877342453036, 6246.437820596168, 6301.842453938688, 6404.967975160281, 11127.165462590176, 11303.830442543533, 11377.715557110807, 11453.02649274821, 11732.648020559396, 9909.801803963735, 11641.59254426091, 11674.241174323643, 11805.586804461802, 11837.053035756946, 8359.916400835913, 8607.913920860712, 8616.698415555426, 8857.51283679378, 8872.33457490678, 9173.0, 9318.95340523293, 9390.107939745712, 9396.248300136109, 9422.62309507612, 8451.45065570736, 8609.397342185986, 8616.439931404439, 8731.650733970824, 8748.65005399777, 8296.626651800803, 8493.405461617627, 8511.872321915052, 8528.74413767588, 8602.354823388396, 7363.15323737777, 7585.810354741176, 7836.37309015266, 7938.404619653434, 7943.44395892282, 5361.919571206513, 5408.810691625774, 5495.395506494203, 5516.531094856982, 5516.558675305886, 5306.257124002565, 5330.147176451749, 5368.8389348319615, 5387.434319396407, 5478.068728316116], "value": 4928.6880765226415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.05872028083604, 354.792446418845, 354.85450965104314, 361.5805665428104, 364.6226155928614, 5416.077292561138, 5465.207544905987, 5477.945220547794, 5525.336959564847, 5525.58558108142, 5467.152591348347, 5471.23402723619, 5476.3702174249975, 5494.972525137378, 5498.230247765306, 4236.682248831326, 4290.806913688876, 4308.418363520916, 4309.762963037032, 4328.805203765838, 4247.617714405718, 4277.807498662568, 4280.550542193072, 4284.443022407083, 4289.270823959934, 4029.9597004030024, 4101.856435024774, 4257.510386305568, 4257.850975215868, 4267.0, 4240.830366785325, 4258.74447533149, 4303.827846886121, 4304.7632380219075, 4312.805923733439, 3807.65731084201, 3819.140693344003, 3822.388417853158, 3830.80845957701, 3844.3272427325105, 5031.811162144442, 5102.6428150029515, 5256.816011439581, 5319.654222475527, 5327.280817089721, 4939.592216218361, 4943.876403089916, 4969.0, 4969.155243608559, 4978.676386034932, 5694.316681998145, 5702.344230413514, 5727.940331038772, 5730.68481233532, 5799.623024503394, 4201.642860356873, 4234.195502854448, 4238.618524332825, 4251.3622956556455, 4264.317709166549, 5869.7945571904775, 5898.852528686774, 5921.555883308769, 5933.614315069507, 5942.910856337116, 4389.18800021997, 4393.462288199169, 4393.692441529062, 4403.251447253974, 4406.405135306757, 4371.606555410029, 4411.360352748833, 4417.4036505072045, 4420.977895110504, 4427.46870375557, 4876.268559716071, 4886.193778026657, 4908.576512811316, 4913.459519452857, 4944.159492886218, 5655.264815573949, 5661.367464129117, 5669.177969194444, 5689.317281926195, 5725.398833122462, 1452.4262916148061, 1535.1173075481643, 1538.1847620761014, 1539.722849887031, 1542.8919975601825, 999.240577161348, 1001.3432653559653, 1003.603576587244, 1014.4876837197169, 1020.6019652335596, 4954.727489988085, 4976.477469865685, 4999.42506611737, 5002.974985125051, 5052.191649336089, 5144.639875208774, 5163.40620828602, 5184.455632158644, 5185.533302002837, 5215.504527069922, 5000.924986125211, 5357.35711714589, 5374.70439125844, 5655.462731040627, 5663.546916246691, 3105.891293804706, 3130.700759184913, 3140.4975203967256, 3141.15188898996, 3141.607299087614, 5819.359870414251, 6138.7237574309265, 6143.201383820075, 6171.84570385744, 6179.104029915637, 6062.848428789315, 6063.363346848606, 6071.301800292935, 6072.696365181723, 6087.66517841523, 6168.814935551941, 6192.0, 6211.254649442186, 6221.9377806222265, 6238.220222472191, 5673.517750991212, 5967.940320596738, 5970.701464926823, 5984.8803023940145, 6008.497875531118, 5843.707814609337, 5845.912311315419, 5860.912086318794, 5922.0, 5925.259342582177, 5834.795782147605, 6109.327973922954, 6147.907781383194, 6151.3541078186145, 6226.501879849692, 4232.682548808913, 4273.551277115858, 4305.4833419988545, 4307.39696442492, 4310.116426132698, 5965.194698715792, 6004.739004809039, 6015.639061656315, 6020.729067192075, 6027.547933904887, 5672.744726487235, 5826.30084389859, 5841.649501029951, 5861.1501332307425, 5868.882622347614, 5816.604015036445, 5836.649801011953, 5852.502537284378, 5869.853253668522, 5882.823515294548, 5483.643563168382, 5778.104393818965, 5780.8554786129, 5781.653100813965, 5811.680357580204, 5844.181814546052, 5858.506080949331, 5859.882802344015, 5878.676672783038, 5881.441418024141, 5486.780528778804, 5752.309722833287, 5761.91357129635, 5790.0, 5802.216700745345], "value": 4402.728872687994, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.6791095873295, 739.6301849075459, 795.7745072588215, 828.6975254032278, 867.2844902955063, 15898.0, 16848.91575542122, 17228.397006104788, 17234.0, 17278.740818887727, 13542.864571354283, 13553.525626603046, 13573.389197486113, 13574.185548867075, 13675.111117777338, 727.8544291141766, 858.4806192253691, 889.3241136736062, 890.363390176024, 896.6996056321141, 880.8898887639045, 886.1271647427279, 888.4624801994797, 889.0398369760675, 890.7951171230621, 931.4039015030386, 933.9066093390675, 934.3833070173687, 934.6308208257734, 934.9205317548017, 753.8643044252033, 895.6059333893098, 920.4477313611847, 921.7603423110006, 923.5982347678781, 675.7972608217515, 679.3240725478181, 681.655763839258, 682.8395327098128, 683.1051322767191, 13727.245001524912, 13832.792508112387, 13857.722845543032, 13905.235212063333, 14006.159630422106, 14732.116073035651, 14878.776818347733, 15252.161131137784, 15308.311125999357, 15319.846801532065, 13330.266835324053, 13371.26458044807, 13513.594592162252, 13583.864161358457, 13687.931560342235, 4647.372604698357, 4673.696209746356, 4694.57748802606, 4701.0, 4734.739589322586, 73931.0, 75201.0, 75469.0, 75561.62219188869, 80735.0, 11957.820632690517, 12339.629811105502, 12364.814527782091, 12445.813312800316, 12521.56174533887, 21294.467638309165, 21411.250606228707, 21561.67657485139, 21564.676529852066, 22366.55266894653, 7889.329407000355, 7957.124716281204, 8116.431849770577, 8163.6514836891965, 8231.259186673227, 4603.746793926366, 4612.60792832613, 4650.790714417859, 4693.225617773098, 4781.258904869751, 3568.3398571264397, 4126.463559737215, 4229.809658565372, 4243.893902652459, 4252.55348188442, 640.286081019669, 643.9130717353191, 685.2291172431015, 712.4905692429937, 716.1978583985968, 600.5351303141379, 619.0868469008177, 634.8730253949228, 678.6674529480518, 699.3530983839928, 9046.231070358961, 9145.451272923647, 9290.16388525036, 9298.526122841338, 9543.61825526985, 649.5810202419508, 671.2515545167104, 675.8918573028391, 692.7713854428026, 695.8677851208254, 4235.364695295731, 4469.245858435612, 4621.792019359137, 4640.86077417678, 4663.953360466419, 124156.0, 125778.37110814387, 127781.0, 133423.33288333495, 134892.0, 106879.4656026715, 109790.0, 109912.4504377473, 110636.0, 110944.0, 94093.5295323519, 94628.0, 94932.52533737425, 96099.51950240343, 97191.0, 9083.0, 9146.0, 9204.539773011189, 9240.168384845396, 9270.771069934357, 56736.0, 57793.711031446255, 58178.0, 59798.0, 61322.386776133455, 81956.18043819486, 84030.15969840223, 85190.57404712937, 85297.57351213205, 87424.0, 11365.829512557153, 12036.75926481483, 12180.75638487243, 12201.572944946884, 12302.507899684271, 12571.18287311322, 12903.354832258537, 13055.0, 13366.665833354244, 13600.523981660594, 11258.548433658103, 11333.716657083638, 11362.659120226406, 11368.431578420881, 11490.195686301879, 12133.150679452707, 12251.571195008131, 12630.87369126297, 12703.36483175819, 12948.28784416866, 11839.467223975114, 11998.820017699567, 12139.635810925687, 12170.391480425766, 12224.877751222373, 10248.846267306146, 10262.384256944608, 10286.639967601099, 10287.84568231462, 10418.114460270961, 9592.95203523978, 9832.26258030665, 9902.207823373968, 9906.455144967096, 9978.700638980843], "value": 7661.752269787861, "unit": "score"}], "name": "dromaeo_css", "value": 7569.088065111942, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 110.0, 111.0, 110.0, 108.0, 109.0, 111.0, 109.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 126.0, 183.0, 124.0, 132.0, 149.0, 124.0, 181.0, 124.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 160.0, 163.0, 159.0, 160.0, 160.0, 159.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 101.0, 75.0, 76.0, 75.0, 76.0, 98.0, 76.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 121.0, 83.0, 86.0, 85.0, 119.0, 84.0, 84.0, 120.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 145.0, 145.0, 145.0], "value": 145.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 96.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 50.0, 49.0, 50.0, 51.0, 51.0, 49.0, 50.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0, 60.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 76.0, 76.0, 82.0, 77.0, 78.0, 112.0, 76.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 117.0, 116.0, 116.0, 117.0, 116.0, 115.0, 117.0, 121.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 161.0, 153.0, 152.0, 151.0, 152.0, 159.0, 154.0, 156.0, 153.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 60.0, 61.0, 62.0, 62.0, 61.0, 65.0, 63.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1467.6999999999998, "alertThreshold": 2.0}]} 17:20:24 INFO - Return code: 0 17:20:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:20:24 ERROR - # TBPL SUCCESS # 17:20:24 INFO - Running post-action listener: _resource_record_post_action 17:20:24 INFO - ##### 17:20:24 INFO - ##### Finished run-tests step (success) 17:20:24 INFO - ##### 17:20:24 INFO - Running post-run listener: _resource_record_post_run 17:20:25 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 28672; Write bytes: 461959168; Read time: 28; Write time: 374132 17:20:25 INFO - TinderboxPrint: CPU usage
13.0% 17:20:25 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 28 17:20:25 INFO - TinderboxPrint: I/O write bytes / time
461,959,168 / 374,132 17:20:25 INFO - TinderboxPrint: CPU idle
8,238.1 (86.9%) 17:20:25 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 17:20:25 INFO - TinderboxPrint: Swap in / out
0 / 0 17:20:25 INFO - install - Wall time: 13s; CPU: 15.0%; Read bytes: 0; Write bytes: 241475584; Read time: 0; Write time: 235360 17:20:25 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220483584; Read time: 20; Write time: 138772 17:20:25 INFO - Running post-run listener: _upload_blobber_files 17:20:25 INFO - Blob upload gear active. 17:20:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:20:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:20:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:20:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:26 INFO - (blobuploader) - INFO - Done attempting. 17:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:20:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:27 INFO - (blobuploader) - INFO - Done attempting. 17:20:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:20:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:35 INFO - (blobuploader) - INFO - Done attempting. 17:20:35 INFO - (blobuploader) - INFO - Iteration through files over. 17:20:35 INFO - Return code: 0 17:20:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:20:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:20:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db726ae4f875f5ce77d20bd6d47055a4b9f85d617fbe80c413eb6c02ff6e1e193d12a7d7ea1fc57be092a31e06bde1eefde7b95e8049a1d75af5726159c1cdd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb6b0f3d9a3e519ae499f7f6a87c29578a873082c94c250e8cb51ea8bff0ba774aaee88bd06cf74af40dc929faa07266de1321aa833347dd40fd44b8122aa0c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d324502e4a27bba1359138a33727c855073c360c69076dc113511272d8e9d27a82bdd48b1be3e5334f104594d58eca52af8cab5ffb8061865de662af77353b86"} 17:20:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:20:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:20:35 INFO - Contents: 17:20:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db726ae4f875f5ce77d20bd6d47055a4b9f85d617fbe80c413eb6c02ff6e1e193d12a7d7ea1fc57be092a31e06bde1eefde7b95e8049a1d75af5726159c1cdd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb6b0f3d9a3e519ae499f7f6a87c29578a873082c94c250e8cb51ea8bff0ba774aaee88bd06cf74af40dc929faa07266de1321aa833347dd40fd44b8122aa0c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d324502e4a27bba1359138a33727c855073c360c69076dc113511272d8e9d27a82bdd48b1be3e5334f104594d58eca52af8cab5ffb8061865de662af77353b86"} 17:20:35 INFO - Running post-run listener: copy_logs_to_upload_dir 17:20:35 INFO - Copying logs to upload dir... 17:20:35 INFO - mkdir: /builds/slave/test/build/upload/logs 17:20:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.369070 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-03 17:20:35.622618) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 17:20:35.626756) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db726ae4f875f5ce77d20bd6d47055a4b9f85d617fbe80c413eb6c02ff6e1e193d12a7d7ea1fc57be092a31e06bde1eefde7b95e8049a1d75af5726159c1cdd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb6b0f3d9a3e519ae499f7f6a87c29578a873082c94c250e8cb51ea8bff0ba774aaee88bd06cf74af40dc929faa07266de1321aa833347dd40fd44b8122aa0c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d324502e4a27bba1359138a33727c855073c360c69076dc113511272d8e9d27a82bdd48b1be3e5334f104594d58eca52af8cab5ffb8061865de662af77353b86"} build_url:https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/LChz6s2kRyKFkbbmOma38w/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db726ae4f875f5ce77d20bd6d47055a4b9f85d617fbe80c413eb6c02ff6e1e193d12a7d7ea1fc57be092a31e06bde1eefde7b95e8049a1d75af5726159c1cdd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb6b0f3d9a3e519ae499f7f6a87c29578a873082c94c250e8cb51ea8bff0ba774aaee88bd06cf74af40dc929faa07266de1321aa833347dd40fd44b8122aa0c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d324502e4a27bba1359138a33727c855073c360c69076dc113511272d8e9d27a82bdd48b1be3e5334f104594d58eca52af8cab5ffb8061865de662af77353b86"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 17:20:35.655875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:20:35.656330) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470268667.719214-2023274342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 17:20:35.739262) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 17:20:35.739612) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 17:20:35.739936) ========= ========= Total master_lag: 0.18 =========