builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1472381194.4 results: success (0) buildid: 20160826110814 builduid: 0dbeb50956e042d4ac8dadee4f76f40d revision: 6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.403271) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.403703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.403973) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.418393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.418700) ========= 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=d2a8c569965ab8367867b893000001a1-1472381193.781026-619647704 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-28 03:46:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-08-28 03:46:34 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116271 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.548649) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.548961) ========= 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=d2a8c569965ab8367867b893000001a1-1472381193.781026-619647704 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013700 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.599010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:34.599437) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f --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 6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f --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=d2a8c569965ab8367867b893000001a1-1472381193.781026-619647704 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-28 03:46:34,644 truncating revision to first 12 chars 2016-08-28 03:46:34,644 Setting DEBUG logging. 2016-08-28 03:46:34,644 attempt 1/10 2016-08-28 03:46:34,644 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6d0c7b6a6ae1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-28 03:46:35,090 unpacking tar archive at: mozilla-beta-6d0c7b6a6ae1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.788605 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:35.401611) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:35.401925) ========= script_repo_revision: 6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:35.402313) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:35.402576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 03:46:35.410476) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-08-28 03:46:35.410827) ========= /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=d2a8c569965ab8367867b893000001a1-1472381193.781026-619647704 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:35 INFO - MultiFileLogger online at 20160828 03:46:35 in /builds/slave/test 03:46:35 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 03:46:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:35 INFO - {'append_to_log': False, 03:46:35 INFO - 'base_work_dir': '/builds/slave/test', 03:46:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:46:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:35 INFO - 'branch': 'Mozilla-Beta', 03:46:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:35 INFO - 'config_files': ('talos/linux_config.py',), 03:46:35 INFO - 'default_actions': ('clobber', 03:46:35 INFO - 'read-buildbot-config', 03:46:35 INFO - 'download-and-extract', 03:46:35 INFO - 'populate-webroot', 03:46:35 INFO - 'create-virtualenv', 03:46:35 INFO - 'install', 03:46:35 INFO - 'run-tests'), 03:46:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:35 INFO - 'download_minidump_stackwalk': True, 03:46:35 INFO - 'download_symbols': 'ondemand', 03:46:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:46:35 INFO - 'tooltool.py': '/tools/tooltool.py', 03:46:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:46:35 INFO - '/tools/misc-python/virtualenv.py')}, 03:46:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:35 INFO - 'installer_path': 'installer.exe', 03:46:35 INFO - 'log_level': 'info', 03:46:35 INFO - 'log_name': 'talos', 03:46:35 INFO - 'log_to_console': True, 03:46:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:35 INFO - 'opt_config_files': (), 03:46:35 INFO - 'pip_index': False, 03:46:35 INFO - 'sps_profile': False, 03:46:35 INFO - 'sps_profile_interval': 0, 03:46:35 INFO - 'suite': 'dromaeojs', 03:46:35 INFO - 'system_bits': '32', 03:46:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:35 INFO - 'title': 'talos-linux64-ix-043', 03:46:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:35 INFO - 'use_talos_json': True, 03:46:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:35 INFO - 'work_dir': 'build'} 03:46:35 INFO - ##### 03:46:35 INFO - ##### Running clobber step. 03:46:35 INFO - ##### 03:46:35 INFO - Running pre-action listener: _resource_record_pre_action 03:46:35 INFO - Running main action method: clobber 03:46:35 INFO - rmtree: /builds/slave/test/build 03:46:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:36 INFO - Running post-action listener: _resource_record_post_action 03:46:36 INFO - ##### 03:46:36 INFO - ##### Finished clobber step (success) 03:46:36 INFO - ##### 03:46:36 INFO - ##### 03:46:36 INFO - ##### Running read-buildbot-config step. 03:46:36 INFO - ##### 03:46:36 INFO - Running pre-action listener: _resource_record_pre_action 03:46:36 INFO - Running main action method: read_buildbot_config 03:46:36 INFO - Using buildbot properties: 03:46:36 INFO - { 03:46:36 INFO - "project": "", 03:46:36 INFO - "product": "firefox", 03:46:36 INFO - "script_repo_revision": "production", 03:46:36 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 03:46:36 INFO - "repository": "", 03:46:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 03:46:36 INFO - "buildid": "20160826110814", 03:46:36 INFO - "pgo_build": "True", 03:46:36 INFO - "basedir": "/builds/slave/test", 03:46:36 INFO - "buildnumber": 360, 03:46:36 INFO - "slavename": "talos-linux64-ix-043", 03:46:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:46:36 INFO - "platform": "ubuntu64_hw", 03:46:36 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 03:46:36 INFO - "branch": "mozilla-beta", 03:46:36 INFO - "repo_path": "releases/mozilla-beta", 03:46:36 INFO - "revision": "6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f", 03:46:36 INFO - "stage_platform": "linux64-pgo", 03:46:36 INFO - "builduid": "0dbeb50956e042d4ac8dadee4f76f40d", 03:46:36 INFO - "slavebuilddir": "test" 03:46:36 INFO - } 03:46:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 03:46:36 INFO - Running post-action listener: _resource_record_post_action 03:46:36 INFO - ##### 03:46:36 INFO - ##### Finished read-buildbot-config step (success) 03:46:36 INFO - ##### 03:46:36 INFO - ##### 03:46:36 INFO - ##### Running download-and-extract step. 03:46:36 INFO - ##### 03:46:36 INFO - Running pre-action listener: _resource_record_pre_action 03:46:36 INFO - Running main action method: download_and_extract 03:46:36 INFO - mkdir: /builds/slave/test/build/tests 03:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:36 INFO - https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:46:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:46:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:46:36 INFO - retry: Failed, sleeping 30 seconds before retrying 03:47:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #2 03:47:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:47:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:47:06 INFO - retry: Failed, sleeping 60 seconds before retrying 03:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #3 03:48:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:48:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:48:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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! 03:48:06 INFO - Caught exception: HTTP Error 404: Not Found 03:48:06 INFO - Caught exception: HTTP Error 404: Not Found 03:48:06 INFO - Caught exception: HTTP Error 404: Not Found 03:48:06 INFO - trying https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:48:06 INFO - Downloading https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:48:06 INFO - Downloaded 1327 bytes. 03:48:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 03:48:06 INFO - Using the following test package requirements: 03:48:06 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 03:48:06 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 03:48:06 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 03:48:06 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'jsshell-linux-x86_64.zip'], 03:48:06 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 03:48:06 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 03:48:06 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 03:48:06 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 03:48:06 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 03:48:06 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 03:48:06 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 03:48:06 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:48: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')]} 03:48:06 INFO - https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:48:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:48:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:48:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:48:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:48:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:48:06 INFO - retry: Failed, sleeping 30 seconds before retrying 03:48:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #2 03:48:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:48:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:48:36 INFO - retry: Failed, sleeping 60 seconds before retrying 03:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #3 03:49:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:49:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:49:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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! 03:49:36 INFO - Caught exception: HTTP Error 404: Not Found 03:49:36 INFO - Caught exception: HTTP Error 404: Not Found 03:49:36 INFO - Caught exception: HTTP Error 404: Not Found 03:49:36 INFO - trying https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:49:36 INFO - Downloading https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:49:37 INFO - Downloaded 21969046 bytes. 03:49:37 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'] 03:49:37 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 03:49:38 INFO - Return code: 0 03:49:38 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 03:49:38 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')]} 03:49:38 INFO - https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:49:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:49:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:49:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:49:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:49:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:49:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:49:38 INFO - retry: Failed, sleeping 30 seconds before retrying 03:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #2 03:50:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:50:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:50:08 INFO - retry: Failed, sleeping 60 seconds before retrying 03:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #3 03:51:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:51:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:51:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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! 03:51:08 INFO - Caught exception: HTTP Error 404: Not Found 03:51:08 INFO - Caught exception: HTTP Error 404: Not Found 03:51:08 INFO - Caught exception: HTTP Error 404: Not Found 03:51:08 INFO - trying https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 03:51:08 INFO - Downloading https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:51:09 INFO - Downloaded 21969046 bytes. 03:51:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:51:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:51:11 INFO - Return code: 0 03:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:11 INFO - https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:51:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 03:51:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 03:51:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:51:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:51:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 03:51:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:51:11 INFO - retry: Failed, sleeping 30 seconds before retrying 03:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #2 03:51:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 03:51:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:51:41 INFO - retry: Failed, sleeping 60 seconds before retrying 03:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #3 03:52:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 03:52:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:52:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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! 03:52:41 INFO - Caught exception: HTTP Error 404: Not Found 03:52:41 INFO - Caught exception: HTTP Error 404: Not Found 03:52:41 INFO - Caught exception: HTTP Error 404: Not Found 03:52:41 INFO - trying https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 03:52:41 INFO - Downloading https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:52:42 INFO - Downloaded 10833369 bytes. 03:52:42 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'] 03:52:42 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 03:52:42 INFO - Return code: 0 03:52:42 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')]} 03:52:42 INFO - https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:52:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:52:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:52:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:52:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:52:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:52:42 INFO - retry: Failed, sleeping 30 seconds before retrying 03:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #2 03:53:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:53:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:53:12 INFO - retry: Failed, sleeping 60 seconds before retrying 03:54:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 #3 03:54:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:54:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:54:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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! 03:54:12 INFO - Caught exception: HTTP Error 404: Not Found 03:54:12 INFO - Caught exception: HTTP Error 404: Not Found 03:54:12 INFO - Caught exception: HTTP Error 404: Not Found 03:54:12 INFO - trying https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:54:12 INFO - Downloading https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:54:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/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 03:54:13 INFO - Downloaded 55208267 bytes. 03:54:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:54:13 INFO - mkdir: /builds/slave/test/properties 03:54:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:54:13 INFO - Writing to file /builds/slave/test/properties/build_url 03:54:13 INFO - Contents: 03:54:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 03:54:14 INFO - Running post-action listener: _resource_record_post_action 03:54:14 INFO - Running post-action listener: set_extra_try_arguments 03:54:14 INFO - ##### 03:54:14 INFO - ##### Finished download-and-extract step (success) 03:54:14 INFO - ##### 03:54:14 INFO - ##### 03:54:14 INFO - ##### Running populate-webroot step. 03:54:14 INFO - ##### 03:54:14 INFO - Running pre-action listener: _resource_record_pre_action 03:54:14 INFO - Running main action method: populate_webroot 03:54:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:54:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:54:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:54:14 INFO - '--tppagecycles', 03:54:14 INFO - '7'], 03:54:14 INFO - 'tests': ['tsvgm']}}, 03:54:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:54:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:54:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:54:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:54:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:54:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:54:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['damp', 'tps']}, 03:54:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'tests': ['damp', 'tps']}, 03:54:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['dromaeo_dom']}, 03:54:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:54:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['a11yr', 03:54:14 INFO - 'ts_paint', 03:54:14 INFO - 'tpaint', 03:54:14 INFO - 'sessionrestore', 03:54:14 INFO - 'sessionrestore_no_auto_restore', 03:54:14 INFO - 'tabpaint']}, 03:54:14 INFO - 'other-e10s': {'tests': ['a11yr', 03:54:14 INFO - 'ts_paint', 03:54:14 INFO - 'tpaint', 03:54:14 INFO - 'sessionrestore', 03:54:14 INFO - 'sessionrestore_no_auto_restore', 03:54:14 INFO - 'tabpaint']}, 03:54:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:54:14 INFO - 'ts_paint', 03:54:14 INFO - 'tpaint', 03:54:14 INFO - 'sessionrestore', 03:54:14 INFO - 'sessionrestore_no_auto_restore', 03:54:14 INFO - 'tabpaint']}, 03:54:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:54:14 INFO - 'ts_paint', 03:54:14 INFO - 'tpaint', 03:54:14 INFO - 'sessionrestore', 03:54:14 INFO - 'sessionrestore_no_auto_restore', 03:54:14 INFO - 'tabpaint']}, 03:54:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['a11yr', 03:54:14 INFO - 'ts_paint', 03:54:14 INFO - 'tpaint', 03:54:14 INFO - 'sessionrestore', 03:54:14 INFO - 'sessionrestore_no_auto_restore', 03:54:14 INFO - 'tabpaint']}, 03:54:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['a11yr', 03:54:14 INFO - 'ts_paint', 03:54:14 INFO - 'tpaint', 03:54:14 INFO - 'sessionrestore', 03:54:14 INFO - 'sessionrestore_no_auto_restore', 03:54:14 INFO - 'tabpaint']}, 03:54:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['tsvgx', 03:54:14 INFO - 'tsvgr_opacity', 03:54:14 INFO - 'tart', 03:54:14 INFO - 'tscrollx', 03:54:14 INFO - 'cart']}, 03:54:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:54:14 INFO - 'tsvgr_opacity', 03:54:14 INFO - 'tart', 03:54:14 INFO - 'tscrollx', 03:54:14 INFO - 'cart']}, 03:54:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'talos_options': ['--disable-e10s'], 03:54:14 INFO - 'tests': ['tp5o']}, 03:54:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'tests': ['tp5o']}, 03:54:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'talos_options': ['--disable-e10s', 03:54:14 INFO - '--xperf_path', 03:54:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:54:14 INFO - 'tests': ['tp5n']}, 03:54:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:14 INFO - 'talos_options': ['--xperf_path', 03:54:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:54:14 INFO - 'tests': ['tp5n']}}, 03:54:14 INFO - 'talos.zip': {'path': '', 03:54:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:54:14 INFO - Running post-action listener: _resource_record_post_action 03:54:14 INFO - ##### 03:54:14 INFO - ##### Finished populate-webroot step (success) 03:54:14 INFO - ##### 03:54:14 INFO - ##### 03:54:14 INFO - ##### Running create-virtualenv step. 03:54:14 INFO - ##### 03:54:14 INFO - Running pre-action listener: _resource_record_pre_action 03:54:14 INFO - Running main action method: create_virtualenv 03:54:14 INFO - Creating virtualenv /builds/slave/test/build/venv 03:54:14 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 03:54:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:54:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:54:14 INFO - Using real prefix '/usr' 03:54:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:54:14 INFO - Installing distribute.............................................................................................................................................................................................done. 03:54:16 INFO - Installing pip.................done. 03:54:16 INFO - Return code: 0 03:54:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:54:16 INFO - Using env: {'DISPLAY': ':0', 03:54:16 INFO - 'HOME': '/home/cltbld', 03:54:16 INFO - 'LANG': 'en_US.UTF-8', 03:54:16 INFO - 'LANGUAGE': 'en_US:en', 03:54:16 INFO - 'LOGNAME': 'cltbld', 03:54:16 INFO - 'MAIL': '/var/mail/cltbld', 03:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:16 INFO - 'MOZ_NO_REMOTE': '1', 03:54:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:16 INFO - 'NO_EM_RESTART': '1', 03:54:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:16 INFO - 'PWD': '/builds/slave/test', 03:54:16 INFO - 'SHELL': '/bin/bash', 03:54:16 INFO - 'SHLVL': '1', 03:54:16 INFO - 'TERM': 'linux', 03:54:16 INFO - 'TMOUT': '86400', 03:54:16 INFO - 'USER': 'cltbld', 03:54:16 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:16 INFO - '_': '/tools/buildbot/bin/python'} 03:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:54:16 INFO - Cleaning up... 03:54:16 INFO - Return code: 0 03:54:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:54:16 INFO - Using env: {'DISPLAY': ':0', 03:54:16 INFO - 'HOME': '/home/cltbld', 03:54:16 INFO - 'LANG': 'en_US.UTF-8', 03:54:16 INFO - 'LANGUAGE': 'en_US:en', 03:54:16 INFO - 'LOGNAME': 'cltbld', 03:54:16 INFO - 'MAIL': '/var/mail/cltbld', 03:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:16 INFO - 'MOZ_NO_REMOTE': '1', 03:54:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:16 INFO - 'NO_EM_RESTART': '1', 03:54:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:16 INFO - 'PWD': '/builds/slave/test', 03:54:16 INFO - 'SHELL': '/bin/bash', 03:54:16 INFO - 'SHLVL': '1', 03:54:16 INFO - 'TERM': 'linux', 03:54:16 INFO - 'TMOUT': '86400', 03:54:16 INFO - 'USER': 'cltbld', 03:54:16 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:16 INFO - '_': '/tools/buildbot/bin/python'} 03:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:17 INFO - Downloading/unpacking psutil>=3.1.1 03:54:17 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 03:54:17 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 03:54:17 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 03:54:17 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 03:54:17 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 03:54:17 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 03:54:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:54:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:54:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:54:19 INFO - Installing collected packages: psutil 03:54:19 INFO - Running setup.py install for psutil 03:54:19 INFO - building 'psutil._psutil_linux' extension 03:54:19 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 03:54:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:54:19 INFO - building 'psutil._psutil_posix' extension 03:54:19 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 03:54:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:54:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:54:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:54:19 INFO - Successfully installed psutil 03:54:19 INFO - Cleaning up... 03:54:19 INFO - Return code: 0 03:54:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:54:19 INFO - Using env: {'DISPLAY': ':0', 03:54:19 INFO - 'HOME': '/home/cltbld', 03:54:19 INFO - 'LANG': 'en_US.UTF-8', 03:54:19 INFO - 'LANGUAGE': 'en_US:en', 03:54:19 INFO - 'LOGNAME': 'cltbld', 03:54:19 INFO - 'MAIL': '/var/mail/cltbld', 03:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:19 INFO - 'MOZ_NO_REMOTE': '1', 03:54:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:19 INFO - 'NO_EM_RESTART': '1', 03:54:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:19 INFO - 'PWD': '/builds/slave/test', 03:54:19 INFO - 'SHELL': '/bin/bash', 03:54:19 INFO - 'SHLVL': '1', 03:54:19 INFO - 'TERM': 'linux', 03:54:19 INFO - 'TMOUT': '86400', 03:54:19 INFO - 'USER': 'cltbld', 03:54:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:19 INFO - '_': '/tools/buildbot/bin/python'} 03:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:54:21 INFO - Installing collected packages: mozsystemmonitor 03:54:21 INFO - Running setup.py install for mozsystemmonitor 03:54:21 INFO - Successfully installed mozsystemmonitor 03:54:21 INFO - Cleaning up... 03:54:21 INFO - Return code: 0 03:54:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:54:21 INFO - Using env: {'DISPLAY': ':0', 03:54:21 INFO - 'HOME': '/home/cltbld', 03:54:21 INFO - 'LANG': 'en_US.UTF-8', 03:54:21 INFO - 'LANGUAGE': 'en_US:en', 03:54:21 INFO - 'LOGNAME': 'cltbld', 03:54:21 INFO - 'MAIL': '/var/mail/cltbld', 03:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:21 INFO - 'MOZ_NO_REMOTE': '1', 03:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:21 INFO - 'NO_EM_RESTART': '1', 03:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:21 INFO - 'PWD': '/builds/slave/test', 03:54:21 INFO - 'SHELL': '/bin/bash', 03:54:21 INFO - 'SHLVL': '1', 03:54:21 INFO - 'TERM': 'linux', 03:54:21 INFO - 'TMOUT': '86400', 03:54:21 INFO - 'USER': 'cltbld', 03:54:21 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:21 INFO - '_': '/tools/buildbot/bin/python'} 03:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:22 INFO - Downloading/unpacking jsonschema==2.5.1 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:54:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:24 INFO - Downloading functools32-3.2.3-2.tar.gz 03:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:54:24 INFO - warning: no files found matching '*.txt' 03:54:24 INFO - no previously-included directories found matching 'build' 03:54:24 INFO - no previously-included directories found matching 'dist' 03:54:24 INFO - no previously-included directories found matching '.git*' 03:54:24 INFO - Installing collected packages: jsonschema, functools32 03:54:24 INFO - Running setup.py install for functools32 03:54:24 INFO - warning: no files found matching '*.txt' 03:54:24 INFO - no previously-included directories found matching 'build' 03:54:24 INFO - no previously-included directories found matching 'dist' 03:54:24 INFO - no previously-included directories found matching '.git*' 03:54:24 INFO - Successfully installed jsonschema functools32 03:54:24 INFO - Cleaning up... 03:54:24 INFO - Return code: 0 03:54:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:54:24 INFO - Using env: {'DISPLAY': ':0', 03:54:24 INFO - 'HOME': '/home/cltbld', 03:54:24 INFO - 'LANG': 'en_US.UTF-8', 03:54:24 INFO - 'LANGUAGE': 'en_US:en', 03:54:24 INFO - 'LOGNAME': 'cltbld', 03:54:24 INFO - 'MAIL': '/var/mail/cltbld', 03:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:24 INFO - 'MOZ_NO_REMOTE': '1', 03:54:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:24 INFO - 'NO_EM_RESTART': '1', 03:54:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:24 INFO - 'PWD': '/builds/slave/test', 03:54:24 INFO - 'SHELL': '/bin/bash', 03:54:24 INFO - 'SHLVL': '1', 03:54:24 INFO - 'TERM': 'linux', 03:54:24 INFO - 'TMOUT': '86400', 03:54:24 INFO - 'USER': 'cltbld', 03:54:24 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:24 INFO - '_': '/tools/buildbot/bin/python'} 03:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:24 INFO - Downloading/unpacking blobuploader==1.2.4 03:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:26 INFO - Downloading blobuploader-1.2.4.tar.gz 03:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:54:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:54:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:27 INFO - Downloading docopt-0.6.1.tar.gz 03:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:54:27 INFO - Installing collected packages: blobuploader, requests, docopt 03:54:27 INFO - Running setup.py install for blobuploader 03:54:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:54:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:54:27 INFO - Running setup.py install for requests 03:54:27 INFO - Running setup.py install for docopt 03:54:27 INFO - Successfully installed blobuploader requests docopt 03:54:27 INFO - Cleaning up... 03:54:28 INFO - Return code: 0 03:54:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:54:28 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')]} 03:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:28 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')]} 03:54:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:28 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 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:28 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 03:54:28 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 03:54:28 INFO - Using env: {'DISPLAY': ':0', 03:54:28 INFO - 'HOME': '/home/cltbld', 03:54:28 INFO - 'LANG': 'en_US.UTF-8', 03:54:28 INFO - 'LANGUAGE': 'en_US:en', 03:54:28 INFO - 'LOGNAME': 'cltbld', 03:54:28 INFO - 'MAIL': '/var/mail/cltbld', 03:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:28 INFO - 'MOZ_NO_REMOTE': '1', 03:54:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:28 INFO - 'NO_EM_RESTART': '1', 03:54:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:28 INFO - 'PWD': '/builds/slave/test', 03:54:28 INFO - 'SHELL': '/bin/bash', 03:54:28 INFO - 'SHLVL': '1', 03:54:28 INFO - 'TERM': 'linux', 03:54:28 INFO - 'TMOUT': '86400', 03:54:28 INFO - 'USER': 'cltbld', 03:54:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:28 INFO - '_': '/tools/buildbot/bin/python'} 03:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:54:28 INFO - Running setup.py (path:/tmp/pip-h0tXWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:54:28 INFO - Running setup.py (path:/tmp/pip-55akle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:54:28 INFO - Running setup.py (path:/tmp/pip-GV4QOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:54:28 INFO - Running setup.py (path:/tmp/pip-rmPj2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:54:28 INFO - Running setup.py (path:/tmp/pip-Exeey1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:54:28 INFO - Running setup.py (path:/tmp/pip-RBY1FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:54:28 INFO - Running setup.py (path:/tmp/pip-sTWeeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:54:28 INFO - Running setup.py (path:/tmp/pip-VzREGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:54:28 INFO - Running setup.py (path:/tmp/pip-xTNvKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:54:28 INFO - Running setup.py (path:/tmp/pip-fbJ7ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:54:28 INFO - Running setup.py (path:/tmp/pip-nCypnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:54:29 INFO - Running setup.py (path:/tmp/pip-Ac0IeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:54:29 INFO - Running setup.py (path:/tmp/pip-gKL3UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:54:29 INFO - Running setup.py (path:/tmp/pip-fZeA8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:29 INFO - Running setup.py (path:/tmp/pip-B5xDDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:54:29 INFO - Running setup.py (path:/tmp/pip-_HnDe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:54:29 INFO - Running setup.py (path:/tmp/pip-rRGEvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:54:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:54:29 INFO - Running setup.py install for manifestparser 03:54:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Running setup.py install for mozcrash 03:54:29 INFO - Running setup.py install for mozdebug 03:54:29 INFO - Running setup.py install for mozdevice 03:54:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Running setup.py install for mozfile 03:54:30 INFO - Running setup.py install for mozhttpd 03:54:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for mozinfo 03:54:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for mozInstall 03:54:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for mozleak 03:54:30 INFO - Running setup.py install for mozlog 03:54:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for moznetwork 03:54:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for mozprocess 03:54:30 INFO - Running setup.py install for mozprofile 03:54:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for mozrunner 03:54:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Running setup.py install for mozscreenshot 03:54:31 INFO - Running setup.py install for moztest 03:54:31 INFO - Running setup.py install for mozversion 03:54:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:54:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:54:31 INFO - Cleaning up... 03:54:31 INFO - Return code: 0 03:54:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:54:31 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')]} 03:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:31 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')]} 03:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:31 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 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:31 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 03:54:31 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 03:54:31 INFO - Using env: {'DISPLAY': ':0', 03:54:31 INFO - 'HOME': '/home/cltbld', 03:54:31 INFO - 'LANG': 'en_US.UTF-8', 03:54:31 INFO - 'LANGUAGE': 'en_US:en', 03:54:31 INFO - 'LOGNAME': 'cltbld', 03:54:31 INFO - 'MAIL': '/var/mail/cltbld', 03:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:31 INFO - 'MOZ_NO_REMOTE': '1', 03:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:31 INFO - 'NO_EM_RESTART': '1', 03:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:31 INFO - 'PWD': '/builds/slave/test', 03:54:31 INFO - 'SHELL': '/bin/bash', 03:54:31 INFO - 'SHLVL': '1', 03:54:31 INFO - 'TERM': 'linux', 03:54:31 INFO - 'TMOUT': '86400', 03:54:31 INFO - 'USER': 'cltbld', 03:54:31 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:31 INFO - '_': '/tools/buildbot/bin/python'} 03:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:54:31 INFO - Running setup.py (path:/tmp/pip-xDT0DX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:54:31 INFO - Running setup.py (path:/tmp/pip-81sksA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:54:31 INFO - Running setup.py (path:/tmp/pip-hkwmHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:54:31 INFO - Running setup.py (path:/tmp/pip-x7xWwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:54:31 INFO - Running setup.py (path:/tmp/pip-DxSHFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:54:31 INFO - Running setup.py (path:/tmp/pip-t_2A5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:54:31 INFO - Running setup.py (path:/tmp/pip-yw7ynv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:54:32 INFO - Running setup.py (path:/tmp/pip-e_QOhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:54:32 INFO - Running setup.py (path:/tmp/pip-FWiRUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:54:32 INFO - Running setup.py (path:/tmp/pip-KZtGi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:54:32 INFO - Running setup.py (path:/tmp/pip-glRxXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:54:32 INFO - Running setup.py (path:/tmp/pip-ZVnjVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:54:32 INFO - Running setup.py (path:/tmp/pip-GShjyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:54:32 INFO - Running setup.py (path:/tmp/pip-dh8l8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:32 INFO - Running setup.py (path:/tmp/pip-Q5Wphc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:54:32 INFO - Running setup.py (path:/tmp/pip-tUQJdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:54:32 INFO - Running setup.py (path:/tmp/pip-8iTCkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:54:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:34 INFO - Downloading blessings-1.6.tar.gz 03:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:54:34 INFO - Installing collected packages: blessings 03:54:34 INFO - Running setup.py install for blessings 03:54:34 INFO - Successfully installed blessings 03:54:34 INFO - Cleaning up... 03:54:34 INFO - Return code: 0 03:54:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:54:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:54:35 INFO - Reading from file tmpfile_stdout 03:54:35 INFO - Current package versions: 03:54:35 INFO - argparse == 1.2.1 03:54:35 INFO - blessings == 1.6 03:54:35 INFO - blobuploader == 1.2.4 03:54:35 INFO - docopt == 0.6.1 03:54:35 INFO - functools32 == 3.2.3-2 03:54:35 INFO - jsonschema == 2.5.1 03:54:35 INFO - manifestparser == 1.1 03:54:35 INFO - mozInstall == 1.12 03:54:35 INFO - mozcrash == 0.17 03:54:35 INFO - mozdebug == 0.1 03:54:35 INFO - mozdevice == 0.48 03:54:35 INFO - mozfile == 1.2 03:54:35 INFO - mozhttpd == 0.7 03:54:35 INFO - mozinfo == 0.9 03:54:35 INFO - mozleak == 0.1 03:54:35 INFO - mozlog == 3.2 03:54:35 INFO - moznetwork == 0.27 03:54:35 INFO - mozprocess == 0.23 03:54:35 INFO - mozprofile == 0.28 03:54:35 INFO - mozrunner == 6.12 03:54:35 INFO - mozscreenshot == 0.1 03:54:35 INFO - mozsystemmonitor == 0.3 03:54:35 INFO - moztest == 0.7 03:54:35 INFO - mozversion == 1.4 03:54:35 INFO - psutil == 3.1.1 03:54:35 INFO - requests == 1.2.3 03:54:35 INFO - wsgiref == 0.1.2 03:54:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 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 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:35 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 03:54:35 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 03:54:35 INFO - Using env: {'DISPLAY': ':0', 03:54:35 INFO - 'HOME': '/home/cltbld', 03:54:35 INFO - 'LANG': 'en_US.UTF-8', 03:54:35 INFO - 'LANGUAGE': 'en_US:en', 03:54:35 INFO - 'LOGNAME': 'cltbld', 03:54:35 INFO - 'MAIL': '/var/mail/cltbld', 03:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:35 INFO - 'MOZ_NO_REMOTE': '1', 03:54:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:35 INFO - 'NO_EM_RESTART': '1', 03:54:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:35 INFO - 'PWD': '/builds/slave/test', 03:54:35 INFO - 'SHELL': '/bin/bash', 03:54:35 INFO - 'SHLVL': '1', 03:54:35 INFO - 'TERM': 'linux', 03:54:35 INFO - 'TMOUT': '86400', 03:54:35 INFO - 'USER': 'cltbld', 03:54:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:35 INFO - '_': '/tools/buildbot/bin/python'} 03:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 INFO - Cleaning up... 03:54:35 INFO - Return code: 0 03:54:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:54:35 INFO - Using env: {'DISPLAY': ':0', 03:54:35 INFO - 'HOME': '/home/cltbld', 03:54:35 INFO - 'LANG': 'en_US.UTF-8', 03:54:35 INFO - 'LANGUAGE': 'en_US:en', 03:54:35 INFO - 'LOGNAME': 'cltbld', 03:54:35 INFO - 'MAIL': '/var/mail/cltbld', 03:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:35 INFO - 'MOZ_NO_REMOTE': '1', 03:54:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:35 INFO - 'NO_EM_RESTART': '1', 03:54:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:35 INFO - 'PWD': '/builds/slave/test', 03:54:35 INFO - 'SHELL': '/bin/bash', 03:54:35 INFO - 'SHLVL': '1', 03:54:35 INFO - 'TERM': 'linux', 03:54:35 INFO - 'TMOUT': '86400', 03:54:35 INFO - 'USER': 'cltbld', 03:54:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:35 INFO - '_': '/tools/buildbot/bin/python'} 03:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:54:35 INFO - Cleaning up... 03:54:35 INFO - Return code: 0 03:54:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f268b427f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, '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': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', '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 03:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:54:35 INFO - Using env: {'DISPLAY': ':0', 03:54:35 INFO - 'HOME': '/home/cltbld', 03:54:35 INFO - 'LANG': 'en_US.UTF-8', 03:54:35 INFO - 'LANGUAGE': 'en_US:en', 03:54:35 INFO - 'LOGNAME': 'cltbld', 03:54:35 INFO - 'MAIL': '/var/mail/cltbld', 03:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:35 INFO - 'MOZ_NO_REMOTE': '1', 03:54:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:35 INFO - 'NO_EM_RESTART': '1', 03:54:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:35 INFO - 'PWD': '/builds/slave/test', 03:54:35 INFO - 'SHELL': '/bin/bash', 03:54:35 INFO - 'SHLVL': '1', 03:54:35 INFO - 'TERM': 'linux', 03:54:35 INFO - 'TMOUT': '86400', 03:54:35 INFO - 'USER': 'cltbld', 03:54:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:35 INFO - '_': '/tools/buildbot/bin/python'} 03:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:35 INFO - Downloading/unpacking flake8 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:54:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:54:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:54:38 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 03:54:38 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 03:54:38 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 03:54:38 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 03:54:38 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 03:54:38 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 03:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:54:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:54:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:54:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:54:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:54:38 INFO - no previously-included directories found matching 'docs/_build' 03:54:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:54:38 INFO - Running setup.py install for pep8 03:54:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:54:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:54:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:54:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:54:38 INFO - no previously-included directories found matching 'docs/_build' 03:54:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:54:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:54:38 INFO - Cleaning up... 03:54:38 INFO - Return code: 0 03:54:38 INFO - Running post-action listener: _resource_record_post_action 03:54:38 INFO - Running post-action listener: _start_resource_monitoring 03:54:38 INFO - Starting resource monitoring. 03:54:38 INFO - ##### 03:54:38 INFO - ##### Finished create-virtualenv step (success) 03:54:38 INFO - ##### 03:54:38 INFO - ##### 03:54:38 INFO - ##### Running install step. 03:54:38 INFO - ##### 03:54:38 INFO - Running pre-action listener: _resource_record_pre_action 03:54:38 INFO - Running main action method: install 03:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:54:38 INFO - Reading from file tmpfile_stdout 03:54:38 INFO - Detecting whether we're running mozinstall >=1.0... 03:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:54:38 INFO - Reading from file tmpfile_stdout 03:54:38 INFO - Output received: 03:54:38 INFO - Usage: mozinstall [options] installer 03:54:38 INFO - Options: 03:54:38 INFO - -h, --help show this help message and exit 03:54:38 INFO - -d DEST, --destination=DEST 03:54:38 INFO - Directory to install application into. [default: 03:54:38 INFO - "/builds/slave/test"] 03:54:38 INFO - --app=APP Application being installed. [default: firefox] 03:54:38 INFO - mkdir: /builds/slave/test/build/application 03:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:54:51 INFO - Reading from file tmpfile_stdout 03:54:51 INFO - Output received: 03:54:51 INFO - /builds/slave/test/build/application/firefox/firefox 03:54:51 INFO - Running post-action listener: _resource_record_post_action 03:54:51 INFO - ##### 03:54:51 INFO - ##### Finished install step (success) 03:54:51 INFO - ##### 03:54:51 INFO - ##### 03:54:52 INFO - ##### Running run-tests step. 03:54:52 INFO - ##### 03:54:52 INFO - Running pre-action listener: _resource_record_pre_action 03:54:52 INFO - Running main action method: run_tests 03:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:54:52 INFO - Python 2.7.3 03:54:52 INFO - Return code: 0 03:54:52 INFO - grabbing minidump binary from tooltool 03:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:52 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 0x1e362e0>, '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 0x1da2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da25d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:54:52 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 03:54:52 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 03:54:52 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 03:54:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:54:52 INFO - Return code: 0 03:54:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:54:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:54:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:54:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:54:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:54:53 INFO - Return code: 0 03:54:53 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/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 03:54:53 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/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 03:54:53 INFO - Using env: {'DISPLAY': ':0', 03:54:53 INFO - 'HOME': '/home/cltbld', 03:54:53 INFO - 'LANG': 'en_US.UTF-8', 03:54:53 INFO - 'LANGUAGE': 'en_US:en', 03:54:53 INFO - 'LOGNAME': 'cltbld', 03:54:53 INFO - 'MAIL': '/var/mail/cltbld', 03:54:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:54:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:53 INFO - 'MOZ_NO_REMOTE': '1', 03:54:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:54:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:54:53 INFO - 'NO_EM_RESTART': '1', 03:54:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:53 INFO - 'PWD': '/builds/slave/test', 03:54:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:54:53 INFO - 'SHELL': '/bin/bash', 03:54:53 INFO - 'SHLVL': '1', 03:54:53 INFO - 'TERM': 'linux', 03:54:53 INFO - 'TMOUT': '86400', 03:54:53 INFO - 'USER': 'cltbld', 03:54:53 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1472381193.781026-619647704', 03:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:53 INFO - '_': '/tools/buildbot/bin/python'} 03:54:53 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/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 03:54:53 INFO - mozversion application_buildid: 20160826110814 03:54:53 INFO - mozversion application_changeset: 6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f 03:54:53 INFO - mozversion application_display_name: Firefox 03:54:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:54:53 INFO - mozversion application_name: Firefox 03:54:53 INFO - mozversion application_remotingname: firefox 03:54:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:54:53 INFO - mozversion application_vendor: Mozilla 03:54:53 INFO - mozversion application_version: 49.0 03:54:53 INFO - mozversion platform_buildid: 20160826110814 03:54:53 INFO - mozversion platform_changeset: 6d0c7b6a6ae1b02a00b670a4686bb1c0689f8a6f 03:54:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:54:53 INFO - mozversion platform_version: 49.0 03:54:53 INFO - using testdate: 1472381693 03:54:53 INFO - actual date: 1472381693 03:54:53 INFO - starting webserver on 'localhost:39343' 03:54:53 INFO - SUITE-START | Running 2 tests 03:54:53 INFO - TEST-START | dromaeo_css 03:54:53 INFO - operating with platform_type : linux_ 03:54:53 INFO - Initialising browser for dromaeo_css test... 03:54:53 INFO - TEST-INFO | started process 7272 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5CLNHv/profile http://localhost:39343/getInfo.html) 03:54:57 INFO - PROCESS | 7272 | __metrics Screen width/height:1600/1200 03:54:57 INFO - PROCESS | 7272 | colorDepth:24 03:54:57 INFO - PROCESS | 7272 | Browser inner width/height: 1024/697 03:54:57 INFO - PROCESS | 7272 | __metrics 03:54:58 INFO - PROCESS | 7272 | 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" 03:54:58 ERROR - PROCESS | 7272 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:54:59 INFO - TEST-INFO | 7272: exit 0 03:54:59 INFO - Browser initialized. 03:54:59 INFO - Running cycle 1/1 for dromaeo_css test... 03:54:59 INFO - TEST-INFO | started process 7364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5CLNHv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:55:11 INFO - PROCESS | 7364 | RSS: Main: 140988416 03:55:11 INFO - PROCESS | 7364 | 03:56:02 INFO - PROCESS | 7364 | 1472381762397 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] 03:58:10 INFO - PROCESS | 7364 | Cycle 1(1): loaded http://localhost:39343/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39343/tests/dromaeo/cssquery-ext.html) 03:58:11 INFO - PROCESS | 7364 | RSS: Main: 252395520 03:58:11 INFO - PROCESS | 7364 | 04:01:14 INFO - PROCESS | 7364 | Cycle 1(1): loaded http://localhost:39343/tests/dromaeo/cssquery-ext.html (next: http://localhost:39343/tests/dromaeo/cssquery-jquery.html) 04:01:14 INFO - PROCESS | 7364 | RSS: Main: 224567296 04:01:14 INFO - PROCESS | 7364 | 04:04:16 INFO - PROCESS | 7364 | Cycle 1(1): loaded http://localhost:39343/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39343/tests/dromaeo/cssquery-mootools.html) 04:04:17 INFO - PROCESS | 7364 | RSS: Main: 232153088 04:04:17 INFO - PROCESS | 7364 | 04:07:18 INFO - PROCESS | 7364 | Cycle 1(1): loaded http://localhost:39343/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39343/tests/dromaeo/cssquery-prototype.html) 04:07:19 INFO - PROCESS | 7364 | RSS: Main: 219475968 04:07:19 INFO - PROCESS | 7364 | 04:10:21 INFO - PROCESS | 7364 | Cycle 1(1): loaded http://localhost:39343/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39343/tests/dromaeo/cssquery-yui.html) 04:10:22 INFO - PROCESS | 7364 | RSS: Main: 215248896 04:10:22 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | Cycle 1(1): loaded http://localhost:39343/tests/dromaeo/cssquery-yui.html (next: http://localhost:39343/tests/dromaeo/cssquery-dojo.html) 04:13:25 INFO - PROCESS | 7364 | RSS: Main: 260034560 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | __start_tp_report 04:13:25 INFO - PROCESS | 7364 | _x_x_mozilla_page_load 04:13:25 INFO - PROCESS | 7364 | _x_x_mozilla_page_load_details 04:13:25 INFO - PROCESS | 7364 | |i|pagename|runs| 04:13:25 INFO - PROCESS | 7364 | |0;dojo.html;3376.2909788944307;3386.2380964283034;3417.2823707021516;3434.0899661589697;3446.9655303446957;5560.054790685583;5638.830835074944;5645.604807663465;5662.207290979266;5668.121441176619;5506.724663766806;5529.419410961851;5560.304961879765;5577.8047768328115;5584.91622625661;4980.800767969277;5003.824866129687;5007.098722229997;5020.272060551218;5049.116404629193;5043.470435604264;5126.461721519243;5129.281900533928;5129.3844738631415;5133.563647090002;5078.060558796616;5093.388793344804;5107.182850743881;5123.308353372285;5126.333576635032;5192.688438693672;5206.7917283308625;5208.6093542984045;5219.243209734586;5248.71132087735;4943.431505376891;4962.503749625044;5018.473060328686;5101.795928162869;5106.923396149061;5402.405735369106;5413.052715774763;5415.864603384907;5430.104032834578;5481.424450432687;5919.0825422059215;5971.61184523009;5972;5974.820755377345;5976.4919981801595;5960.5827592068135;6037.849053773647;6047.848803779897;6060.666663333515;6094.603850749687;5632.464915832989;5654.745536450829;5683.658980461144;5690.943090569082;5693.174489699053;5952.285725712942;6186.783462578788;6187.659678717714;6196.535259855529;6199;5836.124581312837;5859.326177489558;5873.236479257669;5952.613080149777;5961.046232602763;5967.403259674084;5988.9401105989255;5989.970050149721;6046.395360463918;6047.516198704093;5783.508401785811;5870.442307980735;5990.850228744316;6001.939980600226;6003.8198854034445;5961.254843144607;5996.13056106862;6017.157597936292;6020.548458865515;6044.909326360108;4617.445906491175;4620.168369693421;4621.907561848811;4639.164950308977;4644.605208557243;4020.597940205956;4073.9796301018896;4120.464339635888;4123.443335149776;4138;5730.19777231188;5733.483986441157;5771.336296325892;5776.249087618667;5800.216970708984;6001.819945401645;6012.098185272244;6038.788642397583;6083.7566497340495;6097.664628445478;5544.63959842617;5679.971600141973;5686.317641883001;5729.684867332254;5730.885382292414;5236.764345604381;5243.056249875059;5245.160774276097;5248.396434410091;5252.395974462986;6300.495960323163;6315.905261421082;6316.684165791692;6318.589291696025;6320.241571011508;5910.231669882888;5975.864585728697;6004.279486461653;6044.818655440344;6086.056661217518;6245.064030150668;6267.811965641038;6294.307626161027;6297.52768542352;6305.8108256752375;5881.647101173943;5885.823425297248;5896.204012458264;5902.793402230901;5930;5818.24362832846;5944.6730429826775;5995.2206213192;6000.369961154191;6036.215292012011;5871.442212989759;5908.586398952032;5909.113632954919;5912.763489460373;5915.763369465173;5695;5844.590878638454;5880.14737863016;5887.352391236875;5897.882042359043;5487;5707.258056452634;5760.0783874579865;5789.1605717171615;5793.073108302651;5898.675572843535;5935.080062590306;5936.910946335895;5944.970275148769;5955.731992060281;5686.147077938343;5751.971240143773;5757.481826635623;5759.7408116634015;5762.250907382181 04:13:25 INFO - PROCESS | 7364 | |1;ext.html;592.7628948420631;637.0539748473516;640.4332166033059;646.2051676437981;663.1909070933466;32437.351252974928;47188.764056179716;48168;48307.03385932288;48369.51630483699;47169.76415117928;47529;47558;47568.286475702895;47759.0448191036;8242.381821363391;8933.59798809055;9077.409968352036;9083.273338132967;10697.358158510475;10480.056794888458;10837.403942783143;10864.511097000654;10865.239433239676;10869.076128529085;10795.78408431831;10863.076638485738;10866.45667716615;10919.617813376533;10966.835497467544;10856.891431085667;10904.78190436187;10908;10911.781764364669;10912.727181820439;10783.13734901206;10857.67426977184;10870.021698047214;10872.18458615599;10883.074938630227;44464.3330350045;44718.77640611776;45059.77470112661;45083.323750143776;45093.323600146025;3572.1248294168095;3777.0179753264074;3991.5210174779213;4170.04089059515;4246.957530424687;34729.65270347315;34880.651193487996;34933.30133397343;35075.64924350749;35133.121671958404;3587.515685382466;3596;3632.164602141496;3657.6159503252043;3697.2605478904056;367001;370852;375117;376652;529171.3541432307;61427.92860357054;61575.84484543109;61815;61908;61924.69037654873;39671.60328396737;40878.182436351104;66836.6658166706;67066.32933670601;67086.66456667686;17642.5589360267;17883.463496095137;17884.28462861471;17885.821141788674;17886.55283617894;13573.864261357458;14447.927760361132;14621.853781462261;14625.707485850437;15019.173945433105;21151.894240528698;21251.256206032715;21268.25561105354;21307.78692213058;21309;7374.976259733484;7432.3310902019075;7629.732959346507;7668.194839541768;7758.534487930629;1013.6756238003916;1018.4449475036123;1104.7735214281054;1107.678773155792;1148.8334191542347;32918.506222406686;33939;34071.659283407345;34097;34901.82549087238;1142.2175809570472;1200.987990120105;1201.285235285004;1244.975100497985;1258.3371050288742;16954.23705933236;17755.911220443813;17765.918786931623;17848.96567583488;18047.729284060748;535524;542476;542583;542693;542872;63767.04349434762;64217;64435.67782161059;64510;64806;1341.161773891318;1430.5207755401848;1438.9568312950837;1455.3887367305756;1467.9266036698123;31171.84414077915;34028.77163443722;34239;34568.82715586406;34810.47784283289;53351.733241333546;53842.73078634582;53859.19211211913;53956.190657140956;53972.19041714456;397931;402266;402438;402834;404936;26521;26794.196174114808;27544.449111017268;30355.84822075964;30689.846550767103;48122.5187748132;48438.757806212154;48915;49274.01451970869;49349;40054.399184012844;40423.59576404198;41118.58881411267;41923.7903810479;42260;39545.2090958185;40564.79717601393;40795.796021019705;41113.58886411098;41226.79386603048;40451.1909761809;44193.77903110464;45172.548274516834;46018.76990615026;47455.52544474511;39035.80482097571;39434.80282598569;39843.402348964206;39850.60149398469;40366.79816600898;29618.28580491058;29647.89290240859;29988.550171747003;30221.244468887573;30317 04:13:25 INFO - PROCESS | 7364 | |2;jquery.html;427.73266708307307;433.0516169588599;436.42392042503894;438.00721481547095;449.04577772234;19765;19786.30747923821;20581.485462863435;20584.691229631568;20624;16780.362141140926;20092.59814803707;20326.390208293775;20487.282945096926;20507.692384614205;5645.943540564593;5835.679037652927;5840.269966254218;5870.70646467677;5878.911816322759;5632.549396048306;5727.971360143193;5773.451522105414;5860.267466566679;5860.4139586041265;5799.101139323411;5807.070868661015;5819.039858423355;5824.504917082053;5837.036888913324;5680.772769089232;5812.070068788995;5993.130996005599;6001.759929602811;6089.634621922654;5719.027765279912;5905.8818823623715;5906.23218981534;5909.231799866033;5912.201852749909;13998.930005349908;14061.929690351482;14178.71642567143;14205.78691319631;17002.65994680112;2301.746807851135;2331.3239160643393;2335.3577766114286;2379.8096152307776;2397.8920948557356;10722.731931701692;10751.731206719816;10887.455627218607;11219.66341009771;11437.428128593538;1847.3811273223487;1995.2318357432493;2029.3810387831707;2050.98974505128;2110.314147901938;80755.59622201852;82863.5856820712;82951.58524207401;83382.58308708479;84309.57845210796;11500.424978751029;11607.129465290138;11709.414529273672;11782.762809904836;11909.70225744346;16668.58328541796;16687.33250669984;16740.246688899028;16744.16279186036;16756.497305080866;8345.457545259538;8846.380753347332;8887.422317549339;9296.8605470918;9388.718338449857;9002.144796244347;9021.954890225506;9053.909460905306;9134.132257435533;9152.267818574499;5195.064888320063;5254.133068043807;5254.789808407697;5309.2301616266195;5349.598780091431;2151.7955794199524;2154.956900861974;2181.749098853652;2201.438633148537;2206.1837120265604;998.5157198758596;1084.810158222308;1095.479647167589;1115.0187834705569;1126.8422420861086;19159.233630654897;19271.13279902408;19774.307899223746;20102.798972010387;20292;1011.6610935336597;1033.3541536539663;1035.3322107240788;1038.127972503101;1121.0527104596604;10751.892481075245;11031.338119712844;11149.609763658233;11218.607348742755;11278.266912650652;395571;396852;400427;511338;523629.9755348304;102280.48859755654;102445.48777256066;104935.47532262442;105543.47228263809;106283;1079.09895237476;1082.9729256768644;1122.309779485627;1125.0999200639455;1127.6560649002133;17743.201555929754;17914.46256612304;18012.36956706509;18153.727694084337;18284.085795710427;44226.778866105466;45000;45793.08413831771;46219.76890115528;46236;61514;62999.6850015747;64090.67954660197;64562.354376457515;64865.35134648593;8900.109989001048;9043.276537877086;9101.590428430869;9163.221126204347;9491.620335186792;10265.640702575374;13346.332683365987;13539.255340956342;13553.525626603021;13823.930880345937;11649.708757281136;11821.349825759666;11908.345041022827;11962.222455540363;12144.574939877062;11627.244229125079;11726.296422214695;11843.111766617365;11894.405279735805;12048.216865904038;11650.592229271935;11799.292042477478;12035.157538972539;12221.938890305491;12450.377481126088;8837.955810220908;9056;9173.99086100515;9232.169104780602;9243.2143267823;9053;11543.422828858691;12223.44994475233;12280.81578776337;12289.262644241375 04:13:25 INFO - PROCESS | 7364 | |3;mootools.html;1398.8810951069158;1565.6633823727896;1598.8880778345515;1776.618027124168;1939.3406241877758;8627.65489380424;9899.75250618735;10153.593856245741;10201.591936322548;10204.336718113318;9891.060349266827;10101.34341267817;10110.747231319221;10112.646057387992;10115.898841011587;2511.6107003414454;2547.4777670577487;2551.1836212411945;2552.247087109298;2552.6553915221393;2490.6139548370033;2570.460203357288;2572.9485410291877;2573.2537564106387;2574.3306740247485;2461.7661322174454;2465.149523414419;2524.116559204282;2526.1158594491963;2527.4565968316806;2397.125049356981;2402.747711490303;2409.5301416223842;2447.1068060157913;2458.483718419134;2407.879606019692;2426.8058555315533;2433.0146290752095;2437.9512409751705;2448.326710154704;9267.490288034156;9509.714708558755;9750.219982401391;9934.90065099347;9965.501724913725;5825.126231065332;5956.970215148896;5961.314448838396;5962.105684147369;5965.4929331006915;3462.8876385404883;3514.38498262803;3554.5379100717;3563.8574457021687;3611;1148.0987424871437;1152.1243854670427;1153.81538953767;1174.2602160638828;1180.6103985684704;13637.795433068512;13715.245661488614;13739.106958047794;13742.244176570284;13763.793543096861;4933.753312334369;4934.235193545006;5014.824481143142;5016.523430274118;5050.84847454577;10572.788544229072;10638.989296016865;10684.358938463716;10693.304935179189;10694.19793515474;5662.2639056922335;5669;5727.026405511073;5727.05503591911;5732.914006289909;4521.253993091103;4592.931106033412;5150.40770311419;5318.654287471302;5321.73391330432;4201.831926722958;4223.957760422418;4294.720843145185;4318.978405107954;4341.848035318749;1238.4427007846505;1251.7559075980278;1263.3493750718387;1283.9101262911506;1287.1118927939692;969.1616751510035;969.5733877094056;979.4710856137748;981.087588542648;987.0376383026635;5922.111683247547;6017.759289628366;6028.517718582503;6046.969765151146;6068.817935461943;986.127277359542;1001.1340190734962;1033.3851358441716;1048.2138396202847;1051.9789604207872;3921.627445392683;3947.7631342119566;4024.5371782245415;4113.979430102891;4124.649404800625;12355.32045737493;12593.622191334274;12654.746905061847;12694.682632934251;12724;12452.003839692985;12474.75050498985;12540.937295313464;12551.497940082294;12564.874351256552;8858.335624828163;9049.09509049103;9063.13900179482;9067;9072.364934454525;5873.500752436091;5894.970525147347;6040.426159514839;6065.4533094060735;6231.719572619334;11101.611443599435;11223;11511.251768635348;11556.248843825124;11764.764704706029;11716.94141529287;11896.405179741152;11989.040876729667;12062.75874482488;12201.816972745593;7883.763487095396;8559.443636163629;8687.478751274944;8798.560071996246;8849.15932986391;9741.902580974383;9749.561269742737;9761.75595610087;9783.36408133469;9842.458664773507;8221.506709597443;8264.256216940264;8299.502029878227;8327.167283271625;8493.10822363643;8126.379416618728;8127.878081828659;8579.571021449028;8659.696910608098;8660.39377243587;7383.187849336461;7620.31417172435;7633.084029916224;7757.146713861358;7757.72847950319;5103.974480127724;5320.068987927175;5366.973165134149;5391.083515802245;5465.234867118527;5326.307580014573;5326.3874654413685;5382.111951527954;5392.865178370572;5500.944990550043 04:13:25 INFO - PROCESS | 7364 | |4;prototype.html;359.18285899578456;364.4787953226884;369.81139618794407;372.16634738186434;375.2663542773879;5489.972550137254;5511.173324001396;5526.50261476467;5543.556515478762;5548.77804887805;5251.842444726654;5435.619506634537;5491.720935811643;5530.087535556639;5564.888702225953;4239.300515414953;4240.194363071007;4275.0594869128745;4287.271163902128;4293.248681480744;4293.785310734466;4307.504636966758;4314.913701725964;4315.633171180453;4318.805653745589;4216.789160541961;4262.744235345889;4273.487181538219;4310.267254566716;4310.56894310568;4221.2190744712325;4252.894247495673;4263.019505513734;4271.466066741657;4288;3785.9621403785886;3791.7156213284115;3801.6768574671187;3831.540215174197;3861.7682939023466;5266.92099618506;5292.920606190872;5296.761645725952;5297.125974214288;5316.415194328621;4846.176150054499;4847.903041939177;4927.384076990376;4934.235193545006;4948.183549714293;5561.888762224733;5566.972165139189;5594.342687223577;5610.7194640268235;5650.180723795027;4225.94351412147;4231.957680423218;4248.343146172964;4249.086446414036;4261.786910654455;5630.380658127603;5675.5743319250805;5797.594168408255;5834.5040671542965;5892.528597712216;4288.892777680583;4298.914021719548;4326.783660817009;4341.891452713644;4342.913141737147;4318;4335.263005289108;4367.978160109179;4409.162259170747;4423.292273236267;4705.482396936334;4845.88544634736;4895.388076490439;4938.925916111261;4996.800127994912;5713.171785028834;5730.8280751576685;5740.368559458456;5754.309482862083;5775.306963164448;1504.8720858727131;1524.3902439024257;1525.3136088760102;1526.99236503819;1531.540537838644;977.53567055648;1006.3157053203745;1014.5333146752577;1017.7099526634873;1023.4933707814679;5028.472010438852;5033.446320904697;5038.823641172541;5114.41184263807;5131.358580177478;5196.9740151299;5254.7898084076205;5267.578593712494;5306.063286900254;5310.52205301517;4734.95265047352;4989.77954810106;5013.699178049329;5045.8738531537;5059.494050594984;2832.9008484702936;2835.7873159512706;2839.4463079699262;2839.6024556562097;2845.6585209774958;5393.90060577191;5403.432639572867;5408.78364865401;5438.851653596807;5471.945280547223;5128.410232823273;5280.339957505312;5336.879255356419;5337.466253374709;5346.331708536433;5396.83809485716;5444.673319600836;5467.261919640798;5491.615586908878;5495.911553609602;4819.145122096843;5236.921446178234;5256.369235691742;5277.044854881279;5322.228276899906;5179.792808287626;5231.215317702375;5247.737613119405;5254.369475662945;5262.973720124539;5931.288245410406;6044.274687037408;6162.691865406622;6219.689015549295;6240.4071613196675;4343.630791382641;4345.2395830728865;4367.781610919505;4379.5401482843845;4380.145871555016;5741;5944.821655350346;5957.314908785372;5984.341722410619;5999.640021598718;5708.714564271687;5742.684152371493;5772;5792.160136780059;5805.245318108618;5692.743826527733;5742.966266072147;5743.971280143573;5754.395788442153;5807.94192058074;5494.203340515683;5696.857578560569;5770.163326317745;5807.738651760596;5822.679752613478;5870.765169393177;5886.852828679317;5888.4700376966275;5912.852178695395;5940.079287710412;5575.4424557543925;5754.769809207585;5802.216700745345;5835.862006908612;5844.444777746107 04:13:25 INFO - PROCESS | 7364 | |5;yui.html;728.5082569265744;746.0525133080989;760.1790066727934;775.9806004849878;780.1145699630919;14010.859891401084;15407.922960385196;15581.376744930189;15724.292406841705;15735.055896646181;14426.567202983928;14589.92705036476;14593.489227877026;14600.707985840278;14609.707805843904;707.4446559450845;835.9665613375457;865.8051938313861;866.0862789756804;870.7170169694842;835.0438747633956;842.3093063687778;843.2158092973532;843.5065486690294;845.3237410071948;745.3589912675095;926.7127190570911;927.7448701607078;928.9163975242227;929.7443203119162;878.9384743067988;916.5783739479847;918.1919910478736;918.4489306416164;919.8850143732034;673.498243808361;673.9898901516432;675.5136301862651;676.4791110844623;676.671814170127;14102.365393557315;14140.080894741808;14144.575662730134;14152.150870947673;14157.362918668685;14994.250287485582;15111.697766044617;15335.769963450446;15339.386424543005;15385.923070384688;13117.934410327982;13319.53381631648;13332.933335333359;13356.933215333862;13538.59384218475;4589.403377560929;4640.257558790611;4653.581177694024;4681.555252251031;4699.342092107074;75983;77199.61400192963;77295;78503;78839.21160788376;12349.876501234872;12364.319962402154;12571.120021598583;12622.179558328678;12846.421911014028;22329.218477353214;22474.550508989727;22486.100555977577;22495.550088998127;22547.436314091952;8894.688685895962;8962.641494340283;9038.09619038104;9105.317101217302;9134.680286189952;5060.898782024339;5154.329937108152;5168.457311982263;5242.633015688865;5271.65734227274;3698.5561732591723;3789.374753165697;3814.332491813977;3817.961820381761;3818.4463252828186;649.243631169682;681.9795406137824;701.1095908196562;736.4550232827669;750.7447467860953;576.685706290073;613.2763339259627;613.4969325153421;629.8079085878851;702.1363722621204;8930.240929520933;9059.954700226588;9113.544322783968;9272.7681807954;9702.369345992487;593.9227900372924;621.0466933257518;641.4964253061324;691.509028589707;705.7529864547367;4484;4667.533246675373;4736.881577960578;4812.374391329105;4849.345338379274;83476;98551;112023.43988280007;115243;115273;65185.348146518874;92886;94164;101531;104393.47803260935;77380.22619773843;88053.55973220093;88238.55880720555;88467;90215;9572.56923438461;9866.358686685342;9868.901310987085;10042.246831487813;10187.541560629641;40022.799886000386;44236.11527769491;45136.54863451324;48585.51414485951;52724.472755271956;74186.62906685432;75716.62141689476;75825.6208718975;75955;77262;12593.93703031479;12837.743245134858;14148.646283842987;14257.215853128178;14695.3387097583;15493.612659683598;16004.519864404087;16083.195840208176;16119.838801611833;16292.592685182964;14557.92721036388;14582.927085364505;14681;14710.77933830972;15216.163111029;15251.771223431435;15302;15407.38370465169;15963.361465541248;16284.51146465608;14768.409263629335;15240;15257.6185595361;15497.612559685647;15739.921300393426;12300.261984280973;12404;12817.295048772407;12985.675358116123;13076.346182691017;12292.81560776607;12486.250824950532;12492;12758.48966041348;12878.742425151631 04:13:25 INFO - PROCESS | 7364 | __end_tp_report 04:13:25 INFO - PROCESS | 7364 | __start_cc_report 04:13:25 INFO - PROCESS | 7364 | _x_x_mozilla_cycle_collect,848 04:13:25 INFO - PROCESS | 7364 | __end_cc_report 04:13:25 INFO - PROCESS | 7364 | __startTimestamp1472382805425__endTimestamp 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | ------- Summary: start ------- 04:13:25 INFO - PROCESS | 7364 | Number of tests: 6 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | [#0] dojo.html Cycles:165 Average:5581.88 Median:5776.25 stddev:618.84 (10.7%) stddev-sans-first:596.06 04:13:25 INFO - PROCESS | 7364 | Values: 3376.3 3386.2 3417.3 3434.1 3447.0 5560.1 5638.8 5645.6 5662.2 5668.1 5506.7 5529.4 5560.3 5577.8 5584.9 4980.8 5003.8 5007.1 5020.3 5049.1 5043.5 5126.5 5129.3 5129.4 5133.6 5078.1 5093.4 5107.2 5123.3 5126.3 5192.7 5206.8 5208.6 5219.2 5248.7 4943.4 4962.5 5018.5 5101.8 5106.9 5402.4 5413.1 5415.9 5430.1 5481.4 5919.1 5971.6 5972.0 5974.8 5976.5 5960.6 6037.8 6047.8 6060.7 6094.6 5632.5 5654.7 5683.7 5690.9 5693.2 5952.3 6186.8 6187.7 6196.5 6199.0 5836.1 5859.3 5873.2 5952.6 5961.0 5967.4 5988.9 5990.0 6046.4 6047.5 5783.5 5870.4 5990.9 6001.9 6003.8 5961.3 5996.1 6017.2 6020.5 6044.9 4617.4 4620.2 4621.9 4639.2 4644.6 4020.6 4074.0 4120.5 4123.4 4138.0 5730.2 5733.5 5771.3 5776.2 5800.2 6001.8 6012.1 6038.8 6083.8 6097.7 5544.6 5680.0 5686.3 5729.7 5730.9 5236.8 5243.1 5245.2 5248.4 5252.4 6300.5 6315.9 6316.7 6318.6 6320.2 5910.2 5975.9 6004.3 6044.8 6086.1 6245.1 6267.8 6294.3 6297.5 6305.8 5881.6 5885.8 5896.2 5902.8 5930.0 5818.2 5944.7 5995.2 6000.4 6036.2 5871.4 5908.6 5909.1 5912.8 5915.8 5695.0 5844.6 5880.1 5887.4 5897.9 5487.0 5707.3 5760.1 5789.2 5793.1 5898.7 5935.1 5936.9 5945.0 5955.7 5686.1 5752.0 5757.5 5759.7 5762.3 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | [#1] ext.html Cycles:180 Average:62220.43 Median:375884.50 stddev:120426.82 (32.0%) stddev-sans-first:120675.76 04:13:25 INFO - PROCESS | 7364 | Values: 592.8 637.1 640.4 646.2 663.2 32437.4 47188.8 48168.0 48307.0 48369.5 47169.8 47529.0 47558.0 47568.3 47759.0 8242.4 8933.6 9077.4 9083.3 10697.4 10480.1 10837.4 10864.5 10865.2 10869.1 10795.8 10863.1 10866.5 10919.6 10966.8 10856.9 10904.8 10908.0 10911.8 10912.7 10783.1 10857.7 10870.0 10872.2 10883.1 44464.3 44718.8 45059.8 45083.3 45093.3 3572.1 3777.0 3991.5 4170.0 4247.0 34729.7 34880.7 34933.3 35075.6 35133.1 3587.5 3596.0 3632.2 3657.6 3697.3 367001.0 370852.0 375117.0 376652.0 529171.4 61427.9 61575.8 61815.0 61908.0 61924.7 39671.6 40878.2 66836.7 67066.3 67086.7 17642.6 17883.5 17884.3 17885.8 17886.6 13573.9 14447.9 14621.9 14625.7 15019.2 21151.9 21251.3 21268.3 21307.8 21309.0 7375.0 7432.3 7629.7 7668.2 7758.5 1013.7 1018.4 1104.8 1107.7 1148.8 32918.5 33939.0 34071.7 34097.0 34901.8 1142.2 1201.0 1201.3 1245.0 1258.3 16954.2 17755.9 17765.9 17849.0 18047.7 535524.0 542476.0 542583.0 542693.0 542872.0 63767.0 64217.0 64435.7 64510.0 64806.0 1341.2 1430.5 1439.0 1455.4 1467.9 31171.8 34028.8 34239.0 34568.8 34810.5 53351.7 53842.7 53859.2 53956.2 53972.2 397931.0 402266.0 402438.0 402834.0 404936.0 26521.0 26794.2 27544.4 30355.8 30689.8 48122.5 48438.8 48915.0 49274.0 49349.0 40054.4 40423.6 41118.6 41923.8 42260.0 39545.2 40564.8 40795.8 41113.6 41226.8 40451.2 44193.8 45172.5 46018.8 47455.5 39035.8 39434.8 39843.4 39850.6 40366.8 29618.3 29647.9 29988.6 30221.2 30317.0 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | [#2] jquery.html Cycles:180 Average:28493.11 Median:20604.35 stddev:74798.43 (363.0%) stddev-sans-first:74978.57 04:13:25 INFO - PROCESS | 7364 | Values: 427.7 433.1 436.4 438.0 449.0 19765.0 19786.3 20581.5 20584.7 20624.0 16780.4 20092.6 20326.4 20487.3 20507.7 5645.9 5835.7 5840.3 5870.7 5878.9 5632.5 5728.0 5773.5 5860.3 5860.4 5799.1 5807.1 5819.0 5824.5 5837.0 5680.8 5812.1 5993.1 6001.8 6089.6 5719.0 5905.9 5906.2 5909.2 5912.2 13998.9 14061.9 14178.7 14205.8 17002.7 2301.7 2331.3 2335.4 2379.8 2397.9 10722.7 10751.7 10887.5 11219.7 11437.4 1847.4 1995.2 2029.4 2051.0 2110.3 80755.6 82863.6 82951.6 83382.6 84309.6 11500.4 11607.1 11709.4 11782.8 11909.7 16668.6 16687.3 16740.2 16744.2 16756.5 8345.5 8846.4 8887.4 9296.9 9388.7 9002.1 9022.0 9053.9 9134.1 9152.3 5195.1 5254.1 5254.8 5309.2 5349.6 2151.8 2155.0 2181.7 2201.4 2206.2 998.5 1084.8 1095.5 1115.0 1126.8 19159.2 19271.1 19774.3 20102.8 20292.0 1011.7 1033.4 1035.3 1038.1 1121.1 10751.9 11031.3 11149.6 11218.6 11278.3 395571.0 396852.0 400427.0 511338.0 523630.0 102280.5 102445.5 104935.5 105543.5 106283.0 1079.1 1083.0 1122.3 1125.1 1127.7 17743.2 17914.5 18012.4 18153.7 18284.1 44226.8 45000.0 45793.1 46219.8 46236.0 61514.0 62999.7 64090.7 64562.4 64865.4 8900.1 9043.3 9101.6 9163.2 9491.6 10265.6 13346.3 13539.3 13553.5 13823.9 11649.7 11821.3 11908.3 11962.2 12144.6 11627.2 11726.3 11843.1 11894.4 12048.2 11650.6 11799.3 12035.2 12221.9 12450.4 8838.0 9056.0 9174.0 9232.2 9243.2 9053.0 11543.4 12223.4 12280.8 12289.3 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | [#3] mootools.html Cycles:180 Average:6359.77 Median:4163.24 stddev:3772.91 (90.6%) stddev-sans-first:3765.08 04:13:25 INFO - PROCESS | 7364 | Values: 1398.9 1565.7 1598.9 1776.6 1939.3 8627.7 9899.8 10153.6 10201.6 10204.3 9891.1 10101.3 10110.7 10112.6 10115.9 2511.6 2547.5 2551.2 2552.2 2552.7 2490.6 2570.5 2572.9 2573.3 2574.3 2461.8 2465.1 2524.1 2526.1 2527.5 2397.1 2402.7 2409.5 2447.1 2458.5 2407.9 2426.8 2433.0 2438.0 2448.3 9267.5 9509.7 9750.2 9934.9 9965.5 5825.1 5957.0 5961.3 5962.1 5965.5 3462.9 3514.4 3554.5 3563.9 3611.0 1148.1 1152.1 1153.8 1174.3 1180.6 13637.8 13715.2 13739.1 13742.2 13763.8 4933.8 4934.2 5014.8 5016.5 5050.8 10572.8 10639.0 10684.4 10693.3 10694.2 5662.3 5669.0 5727.0 5727.1 5732.9 4521.3 4592.9 5150.4 5318.7 5321.7 4201.8 4224.0 4294.7 4319.0 4341.8 1238.4 1251.8 1263.3 1283.9 1287.1 969.2 969.6 979.5 981.1 987.0 5922.1 6017.8 6028.5 6047.0 6068.8 986.1 1001.1 1033.4 1048.2 1052.0 3921.6 3947.8 4024.5 4114.0 4124.6 12355.3 12593.6 12654.7 12694.7 12724.0 12452.0 12474.8 12540.9 12551.5 12564.9 8858.3 9049.1 9063.1 9067.0 9072.4 5873.5 5895.0 6040.4 6065.5 6231.7 11101.6 11223.0 11511.3 11556.2 11764.8 11716.9 11896.4 11989.0 12062.8 12201.8 7883.8 8559.4 8687.5 8798.6 8849.2 9741.9 9749.6 9761.8 9783.4 9842.5 8221.5 8264.3 8299.5 8327.2 8493.1 8126.4 8127.9 8579.6 8659.7 8660.4 7383.2 7620.3 7633.1 7757.1 7757.7 5104.0 5320.1 5367.0 5391.1 5465.2 5326.3 5326.4 5382.1 5392.9 5500.9 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | [#4] prototype.html Cycles:180 Average:4720.72 Median:5249.79 stddev:1344.50 (25.6%) stddev-sans-first:1307.81 04:13:25 INFO - PROCESS | 7364 | Values: 359.2 364.5 369.8 372.2 375.3 5490.0 5511.2 5526.5 5543.6 5548.8 5251.8 5435.6 5491.7 5530.1 5564.9 4239.3 4240.2 4275.1 4287.3 4293.2 4293.8 4307.5 4314.9 4315.6 4318.8 4216.8 4262.7 4273.5 4310.3 4310.6 4221.2 4252.9 4263.0 4271.5 4288.0 3786.0 3791.7 3801.7 3831.5 3861.8 5266.9 5292.9 5296.8 5297.1 5316.4 4846.2 4847.9 4927.4 4934.2 4948.2 5561.9 5567.0 5594.3 5610.7 5650.2 4225.9 4232.0 4248.3 4249.1 4261.8 5630.4 5675.6 5797.6 5834.5 5892.5 4288.9 4298.9 4326.8 4341.9 4342.9 4318.0 4335.3 4368.0 4409.2 4423.3 4705.5 4845.9 4895.4 4938.9 4996.8 5713.2 5730.8 5740.4 5754.3 5775.3 1504.9 1524.4 1525.3 1527.0 1531.5 977.5 1006.3 1014.5 1017.7 1023.5 5028.5 5033.4 5038.8 5114.4 5131.4 5197.0 5254.8 5267.6 5306.1 5310.5 4735.0 4989.8 5013.7 5045.9 5059.5 2832.9 2835.8 2839.4 2839.6 2845.7 5393.9 5403.4 5408.8 5438.9 5471.9 5128.4 5280.3 5336.9 5337.5 5346.3 5396.8 5444.7 5467.3 5491.6 5495.9 4819.1 5236.9 5256.4 5277.0 5322.2 5179.8 5231.2 5247.7 5254.4 5263.0 5931.3 6044.3 6162.7 6219.7 6240.4 4343.6 4345.2 4367.8 4379.5 4380.1 5741.0 5944.8 5957.3 5984.3 5999.6 5708.7 5742.7 5772.0 5792.2 5805.2 5692.7 5743.0 5744.0 5754.4 5807.9 5494.2 5696.9 5770.2 5807.7 5822.7 5870.8 5886.9 5888.5 5912.9 5940.1 5575.4 5754.8 5802.2 5835.9 5844.4 04:13:25 INFO - PROCESS | 7364 | 04:13:25 INFO - PROCESS | 7364 | [#5] yui.html Cycles:180 Average:20670.96 Median:4690.45 stddev:28613.35 (610.0%) stddev-sans-first:28654.44 04:13:25 INFO - PROCESS | 7364 | Values: 728.5 746.1 760.2 776.0 780.1 14010.9 15407.9 15581.4 15724.3 15735.1 14426.6 14589.9 14593.5 14600.7 14609.7 707.4 836.0 865.8 866.1 870.7 835.0 842.3 843.2 843.5 845.3 745.4 926.7 927.7 928.9 929.7 878.9 916.6 918.2 918.4 919.9 673.5 674.0 675.5 676.5 676.7 14102.4 14140.1 14144.6 14152.2 14157.4 14994.3 15111.7 15335.8 15339.4 15385.9 13117.9 13319.5 13332.9 13356.9 13538.6 4589.4 4640.3 4653.6 4681.6 4699.3 75983.0 77199.6 77295.0 78503.0 78839.2 12349.9 12364.3 12571.1 12622.2 12846.4 22329.2 22474.6 22486.1 22495.6 22547.4 8894.7 8962.6 9038.1 9105.3 9134.7 5060.9 5154.3 5168.5 5242.6 5271.7 3698.6 3789.4 3814.3 3818.0 3818.4 649.2 682.0 701.1 736.5 750.7 576.7 613.3 613.5 629.8 702.1 8930.2 9060.0 9113.5 9272.8 9702.4 593.9 621.0 641.5 691.5 705.8 4484.0 4667.5 4736.9 4812.4 4849.3 83476.0 98551.0 112023.4 115243.0 115273.0 65185.3 92886.0 94164.0 101531.0 104393.5 77380.2 88053.6 88238.6 88467.0 90215.0 9572.6 9866.4 9868.9 10042.2 10187.5 40022.8 44236.1 45136.5 48585.5 52724.5 74186.6 75716.6 75825.6 75955.0 77262.0 12593.9 12837.7 14148.6 14257.2 14695.3 15493.6 16004.5 16083.2 16119.8 16292.6 14557.9 14582.9 14681.0 14710.8 15216.2 15251.8 15302.0 15407.4 15963.4 16284.5 14768.4 15240.0 15257.6 15497.6 15739.9 12300.3 12404.0 12817.3 12985.7 13076.3 12292.8 12486.3 12492.0 12758.5 12878.7 04:13:25 INFO - PROCESS | 7364 | -------- Summary: end -------- 04:13:25 INFO - PROCESS | 7364 | 04:13:26 INFO - TEST-INFO | 7364: exit 0 04:13:26 INFO - TEST-OK | dromaeo_css | took 1112988ms 04:13:26 INFO - TEST-START | kraken 04:13:26 INFO - operating with platform_type : linux_ 04:13:26 INFO - Initialising browser for kraken test... 04:13:26 INFO - TEST-INFO | started process 7561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY_Dyxw/profile http://localhost:39343/getInfo.html) 04:13:30 INFO - PROCESS | 7561 | __metrics Screen width/height:1600/1200 04:13:30 INFO - PROCESS | 7561 | colorDepth:24 04:13:30 INFO - PROCESS | 7561 | Browser inner width/height: 1024/697 04:13:30 INFO - PROCESS | 7561 | __metrics 04:13:31 INFO - PROCESS | 7561 | 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" 04:13:31 ERROR - PROCESS | 7561 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:13:31 INFO - TEST-INFO | 7561: exit 0 04:13:31 INFO - Browser initialized. 04:13:31 INFO - Running cycle 1/1 for kraken test... 04:13:31 INFO - TEST-INFO | started process 7652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY_Dyxw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:13:43 INFO - PROCESS | 7652 | RSS: Main: 144576512 04:13:43 INFO - PROCESS | 7652 | 04:13:46 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39343/tests/kraken/driver.html?testName=audio-beat-detection) 04:13:47 INFO - PROCESS | 7652 | RSS: Main: 194408448 04:13:47 INFO - PROCESS | 7652 | 04:13:49 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39343/tests/kraken/driver.html?testName=audio-dft) 04:13:50 INFO - PROCESS | 7652 | RSS: Main: 193257472 04:13:50 INFO - PROCESS | 7652 | 04:13:53 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39343/tests/kraken/driver.html?testName=audio-fft) 04:13:53 INFO - PROCESS | 7652 | RSS: Main: 279449600 04:13:53 INFO - PROCESS | 7652 | 04:13:55 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39343/tests/kraken/driver.html?testName=audio-oscillator) 04:13:56 INFO - PROCESS | 7652 | RSS: Main: 187404288 04:13:56 INFO - PROCESS | 7652 | 04:13:58 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39343/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:13:59 INFO - PROCESS | 7652 | RSS: Main: 188329984 04:13:59 INFO - PROCESS | 7652 | 04:14:02 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39343/tests/kraken/driver.html?testName=imaging-darkroom) 04:14:02 INFO - PROCESS | 7652 | RSS: Main: 223395840 04:14:02 INFO - PROCESS | 7652 | 04:14:06 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39343/tests/kraken/driver.html?testName=imaging-desaturate) 04:14:06 INFO - PROCESS | 7652 | RSS: Main: 223764480 04:14:06 INFO - PROCESS | 7652 | 04:14:09 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39343/tests/kraken/driver.html?testName=json-parse-financial) 04:14:09 INFO - PROCESS | 7652 | RSS: Main: 223158272 04:14:09 INFO - PROCESS | 7652 | 04:14:11 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39343/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:14:12 INFO - PROCESS | 7652 | RSS: Main: 203124736 04:14:12 INFO - PROCESS | 7652 | 04:14:13 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:14:14 INFO - PROCESS | 7652 | RSS: Main: 202682368 04:14:14 INFO - PROCESS | 7652 | 04:14:16 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:14:17 INFO - PROCESS | 7652 | RSS: Main: 205774848 04:14:17 INFO - PROCESS | 7652 | 04:14:19 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:14:20 INFO - PROCESS | 7652 | RSS: Main: 210792448 04:14:20 INFO - PROCESS | 7652 | 04:14:22 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:14:23 INFO - PROCESS | 7652 | RSS: Main: 211849216 04:14:23 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | Cycle 1(1): loaded http://localhost:39343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39343/tests/kraken/driver.html?testName=ai-astar) 04:14:25 INFO - PROCESS | 7652 | RSS: Main: 254234624 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | __start_tp_report 04:14:25 INFO - PROCESS | 7652 | _x_x_mozilla_page_load 04:14:25 INFO - PROCESS | 7652 | _x_x_mozilla_page_load_details 04:14:25 INFO - PROCESS | 7652 | |i|pagename|runs| 04:14:25 INFO - PROCESS | 7652 | |0;ai-astar;110;103;109;110;112;111;113;110;109;110 04:14:25 INFO - PROCESS | 7652 | |1;audio-beat-detection;165;123;128;187;127;125;151;125;199;126 04:14:25 INFO - PROCESS | 7652 | |2;audio-dft;162;161;161;160;159;161;160;160;160;159 04:14:25 INFO - PROCESS | 7652 | |3;audio-fft;76;75;74;125;75;75;76;74;103;76 04:14:25 INFO - PROCESS | 7652 | |4;audio-oscillator;84;84;115;85;86;85;123;84;85;124 04:14:25 INFO - PROCESS | 7652 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;117;117 04:14:25 INFO - PROCESS | 7652 | |6;imaging-darkroom;148;146;145;145;147;145;146;145;147;145 04:14:25 INFO - PROCESS | 7652 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 04:14:25 INFO - PROCESS | 7652 | |8;json-parse-financial;57;51;51;53;54;53;52;52;52;52 04:14:25 INFO - PROCESS | 7652 | |9;json-stringify-tinderbox;62;63;62;62;62;61;62;62;62;62 04:14:25 INFO - PROCESS | 7652 | |10;stanford-crypto-aes;76;77;81;83;81;82;81;77;140;76 04:14:25 INFO - PROCESS | 7652 | |11;stanford-crypto-ccm;144;123;134;117;119;125;133;132;134;118 04:14:25 INFO - PROCESS | 7652 | |12;stanford-crypto-pbkdf2;154;156;155;154;153;154;152;154;151;151 04:14:25 INFO - PROCESS | 7652 | |13;stanford-crypto-sha256-iterative;63;63;62;62;63;65;62;61;60;62 04:14:25 INFO - PROCESS | 7652 | __end_tp_report 04:14:25 INFO - PROCESS | 7652 | __start_cc_report 04:14:25 INFO - PROCESS | 7652 | _x_x_mozilla_cycle_collect,1568 04:14:25 INFO - PROCESS | 7652 | __end_cc_report 04:14:25 INFO - PROCESS | 7652 | __startTimestamp1472382865357__endTimestamp 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | ------- Summary: start ------- 04:14:25 INFO - PROCESS | 7652 | Number of tests: 14 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:2.67 (2.4%) stddev-sans-first:2.83 04:14:25 INFO - PROCESS | 7652 | Values: 110.0 103.0 109.0 110.0 112.0 111.0 113.0 110.0 109.0 110.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:139.50 stddev:28.62 (20.5%) stddev-sans-first:29.48 04:14:25 INFO - PROCESS | 7652 | Values: 165.0 123.0 128.0 187.0 127.0 125.0 151.0 125.0 199.0 126.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:0.78 04:14:25 INFO - PROCESS | 7652 | Values: 162.0 161.0 161.0 160.0 159.0 161.0 160.0 160.0 160.0 159.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:17.21 (22.9%) stddev-sans-first:18.07 04:14:25 INFO - PROCESS | 7652 | Values: 76.0 75.0 74.0 125.0 75.0 75.0 76.0 74.0 103.0 76.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:84.50 stddev:17.53 (20.7%) stddev-sans-first:18.10 04:14:25 INFO - PROCESS | 7652 | Values: 84.0 84.0 115.0 85.0 86.0 85.0 123.0 84.0 85.0 124.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:14:25 INFO - PROCESS | 7652 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:1.10 (0.8%) stddev-sans-first:0.87 04:14:25 INFO - PROCESS | 7652 | Values: 148.0 146.0 145.0 145.0 147.0 145.0 146.0 145.0 147.0 145.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:14:25 INFO - PROCESS | 7652 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.50 stddev:1.77 (3.4%) stddev-sans-first:0.97 04:14:25 INFO - PROCESS | 7652 | Values: 57.0 51.0 51.0 53.0 54.0 53.0 52.0 52.0 52.0 52.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 04:14:25 INFO - PROCESS | 7652 | Values: 62.0 63.0 62.0 62.0 62.0 61.0 62.0 62.0 62.0 62.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:81.00 stddev:19.36 (23.9%) stddev-sans-first:20.24 04:14:25 INFO - PROCESS | 7652 | Values: 76.0 77.0 81.0 83.0 81.0 82.0 81.0 77.0 140.0 76.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:132.50 stddev:8.85 (6.7%) stddev-sans-first:7.22 04:14:25 INFO - PROCESS | 7652 | Values: 144.0 123.0 134.0 117.0 119.0 125.0 133.0 132.0 134.0 118.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:154.00 stddev:1.65 (1.1%) stddev-sans-first:1.73 04:14:25 INFO - PROCESS | 7652 | Values: 154.0 156.0 155.0 154.0 153.0 154.0 152.0 154.0 151.0 151.0 04:14:25 INFO - PROCESS | 7652 | 04:14:25 INFO - PROCESS | 7652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.34 (2.1%) stddev-sans-first:1.39 04:14:25 INFO - PROCESS | 7652 | Values: 63.0 63.0 62.0 62.0 63.0 65.0 62.0 61.0 60.0 62.0 04:14:25 INFO - PROCESS | 7652 | -------- Summary: end -------- 04:14:25 INFO - PROCESS | 7652 | 04:14:26 INFO - TEST-INFO | 7652: exit 0 04:14:26 INFO - TEST-OK | kraken | took 60212ms 04:14:26 INFO - SUITE-END | took 1173s 04:14:26 INFO - Completed test suite (00:19:33) 04:14:26 INFO - javascript benchmark 04:14:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.2909788944307, 3386.2380964283034, 3417.2823707021516, 3434.0899661589697, 3446.9655303446957, 5560.054790685583, 5638.830835074944, 5645.604807663465, 5662.207290979266, 5668.121441176619, 5506.724663766806, 5529.419410961851, 5560.304961879765, 5577.8047768328115, 5584.91622625661, 4980.800767969277, 5003.824866129687, 5007.098722229997, 5020.272060551218, 5049.116404629193, 5043.470435604264, 5126.461721519243, 5129.281900533928, 5129.3844738631415, 5133.563647090002, 5078.060558796616, 5093.388793344804, 5107.182850743881, 5123.308353372285, 5126.333576635032, 5192.688438693672, 5206.7917283308625, 5208.6093542984045, 5219.243209734586, 5248.71132087735, 4943.431505376891, 4962.503749625044, 5018.473060328686, 5101.795928162869, 5106.923396149061, 5402.405735369106, 5413.052715774763, 5415.864603384907, 5430.104032834578, 5481.424450432687, 5919.0825422059215, 5971.61184523009, 5972.0, 5974.820755377345, 5976.4919981801595, 5960.5827592068135, 6037.849053773647, 6047.848803779897, 6060.666663333515, 6094.603850749687, 5632.464915832989, 5654.745536450829, 5683.658980461144, 5690.943090569082, 5693.174489699053, 5952.285725712942, 6186.783462578788, 6187.659678717714, 6196.535259855529, 6199.0, 5836.124581312837, 5859.326177489558, 5873.236479257669, 5952.613080149777, 5961.046232602763, 5967.403259674084, 5988.9401105989255, 5989.970050149721, 6046.395360463918, 6047.516198704093, 5783.508401785811, 5870.442307980735, 5990.850228744316, 6001.939980600226, 6003.8198854034445, 5961.254843144607, 5996.13056106862, 6017.157597936292, 6020.548458865515, 6044.909326360108, 4617.445906491175, 4620.168369693421, 4621.907561848811, 4639.164950308977, 4644.605208557243, 4020.597940205956, 4073.9796301018896, 4120.464339635888, 4123.443335149776, 4138.0, 5730.19777231188, 5733.483986441157, 5771.336296325892, 5776.249087618667, 5800.216970708984, 6001.819945401645, 6012.098185272244, 6038.788642397583, 6083.7566497340495, 6097.664628445478, 5544.63959842617, 5679.971600141973, 5686.317641883001, 5729.684867332254, 5730.885382292414, 5236.764345604381, 5243.056249875059, 5245.160774276097, 5248.396434410091, 5252.395974462986, 6300.495960323163, 6315.905261421082, 6316.684165791692, 6318.589291696025, 6320.241571011508, 5910.231669882888, 5975.864585728697, 6004.279486461653, 6044.818655440344, 6086.056661217518, 6245.064030150668, 6267.811965641038, 6294.307626161027, 6297.52768542352, 6305.8108256752375, 5881.647101173943, 5885.823425297248, 5896.204012458264, 5902.793402230901, 5930.0, 5818.24362832846, 5944.6730429826775, 5995.2206213192, 6000.369961154191, 6036.215292012011, 5871.442212989759, 5908.586398952032, 5909.113632954919, 5912.763489460373, 5915.763369465173, 5695.0, 5844.590878638454, 5880.14737863016, 5887.352391236875, 5897.882042359043, 5487.0, 5707.258056452634, 5760.0783874579865, 5789.1605717171615, 5793.073108302651, 5898.675572843535, 5935.080062590306, 5936.910946335895, 5944.970275148769, 5955.731992060281, 5686.147077938343, 5751.971240143773, 5757.481826635623, 5759.7408116634015, 5762.250907382181], "value": 5542.028546158656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.7628948420631, 637.0539748473516, 640.4332166033059, 646.2051676437981, 663.1909070933466, 32437.351252974928, 47188.764056179716, 48168.0, 48307.03385932288, 48369.51630483699, 47169.76415117928, 47529.0, 47558.0, 47568.286475702895, 47759.0448191036, 8242.381821363391, 8933.59798809055, 9077.409968352036, 9083.273338132967, 10697.358158510475, 10480.056794888458, 10837.403942783143, 10864.511097000654, 10865.239433239676, 10869.076128529085, 10795.78408431831, 10863.076638485738, 10866.45667716615, 10919.617813376533, 10966.835497467544, 10856.891431085667, 10904.78190436187, 10908.0, 10911.781764364669, 10912.727181820439, 10783.13734901206, 10857.67426977184, 10870.021698047214, 10872.18458615599, 10883.074938630227, 44464.3330350045, 44718.77640611776, 45059.77470112661, 45083.323750143776, 45093.323600146025, 3572.1248294168095, 3777.0179753264074, 3991.5210174779213, 4170.04089059515, 4246.957530424687, 34729.65270347315, 34880.651193487996, 34933.30133397343, 35075.64924350749, 35133.121671958404, 3587.515685382466, 3596.0, 3632.164602141496, 3657.6159503252043, 3697.2605478904056, 367001.0, 370852.0, 375117.0, 376652.0, 529171.3541432307, 61427.92860357054, 61575.84484543109, 61815.0, 61908.0, 61924.69037654873, 39671.60328396737, 40878.182436351104, 66836.6658166706, 67066.32933670601, 67086.66456667686, 17642.5589360267, 17883.463496095137, 17884.28462861471, 17885.821141788674, 17886.55283617894, 13573.864261357458, 14447.927760361132, 14621.853781462261, 14625.707485850437, 15019.173945433105, 21151.894240528698, 21251.256206032715, 21268.25561105354, 21307.78692213058, 21309.0, 7374.976259733484, 7432.3310902019075, 7629.732959346507, 7668.194839541768, 7758.534487930629, 1013.6756238003916, 1018.4449475036123, 1104.7735214281054, 1107.678773155792, 1148.8334191542347, 32918.506222406686, 33939.0, 34071.659283407345, 34097.0, 34901.82549087238, 1142.2175809570472, 1200.987990120105, 1201.285235285004, 1244.975100497985, 1258.3371050288742, 16954.23705933236, 17755.911220443813, 17765.918786931623, 17848.96567583488, 18047.729284060748, 535524.0, 542476.0, 542583.0, 542693.0, 542872.0, 63767.04349434762, 64217.0, 64435.67782161059, 64510.0, 64806.0, 1341.161773891318, 1430.5207755401848, 1438.9568312950837, 1455.3887367305756, 1467.9266036698123, 31171.84414077915, 34028.77163443722, 34239.0, 34568.82715586406, 34810.47784283289, 53351.733241333546, 53842.73078634582, 53859.19211211913, 53956.190657140956, 53972.19041714456, 397931.0, 402266.0, 402438.0, 402834.0, 404936.0, 26521.0, 26794.196174114808, 27544.449111017268, 30355.84822075964, 30689.846550767103, 48122.5187748132, 48438.757806212154, 48915.0, 49274.01451970869, 49349.0, 40054.399184012844, 40423.59576404198, 41118.58881411267, 41923.7903810479, 42260.0, 39545.2090958185, 40564.79717601393, 40795.796021019705, 41113.58886411098, 41226.79386603048, 40451.1909761809, 44193.77903110464, 45172.548274516834, 46018.76990615026, 47455.52544474511, 39035.80482097571, 39434.80282598569, 39843.402348964206, 39850.60149398469, 40366.79816600898, 29618.28580491058, 29647.89290240859, 29988.550171747003, 30221.244468887573, 30317.0], "value": 21411.343334115358, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.73266708307307, 433.0516169588599, 436.42392042503894, 438.00721481547095, 449.04577772234, 19765.0, 19786.30747923821, 20581.485462863435, 20584.691229631568, 20624.0, 16780.362141140926, 20092.59814803707, 20326.390208293775, 20487.282945096926, 20507.692384614205, 5645.943540564593, 5835.679037652927, 5840.269966254218, 5870.70646467677, 5878.911816322759, 5632.549396048306, 5727.971360143193, 5773.451522105414, 5860.267466566679, 5860.4139586041265, 5799.101139323411, 5807.070868661015, 5819.039858423355, 5824.504917082053, 5837.036888913324, 5680.772769089232, 5812.070068788995, 5993.130996005599, 6001.759929602811, 6089.634621922654, 5719.027765279912, 5905.8818823623715, 5906.23218981534, 5909.231799866033, 5912.201852749909, 13998.930005349908, 14061.929690351482, 14178.71642567143, 14205.78691319631, 17002.65994680112, 2301.746807851135, 2331.3239160643393, 2335.3577766114286, 2379.8096152307776, 2397.8920948557356, 10722.731931701692, 10751.731206719816, 10887.455627218607, 11219.66341009771, 11437.428128593538, 1847.3811273223487, 1995.2318357432493, 2029.3810387831707, 2050.98974505128, 2110.314147901938, 80755.59622201852, 82863.5856820712, 82951.58524207401, 83382.58308708479, 84309.57845210796, 11500.424978751029, 11607.129465290138, 11709.414529273672, 11782.762809904836, 11909.70225744346, 16668.58328541796, 16687.33250669984, 16740.246688899028, 16744.16279186036, 16756.497305080866, 8345.457545259538, 8846.380753347332, 8887.422317549339, 9296.8605470918, 9388.718338449857, 9002.144796244347, 9021.954890225506, 9053.909460905306, 9134.132257435533, 9152.267818574499, 5195.064888320063, 5254.133068043807, 5254.789808407697, 5309.2301616266195, 5349.598780091431, 2151.7955794199524, 2154.956900861974, 2181.749098853652, 2201.438633148537, 2206.1837120265604, 998.5157198758596, 1084.810158222308, 1095.479647167589, 1115.0187834705569, 1126.8422420861086, 19159.233630654897, 19271.13279902408, 19774.307899223746, 20102.798972010387, 20292.0, 1011.6610935336597, 1033.3541536539663, 1035.3322107240788, 1038.127972503101, 1121.0527104596604, 10751.892481075245, 11031.338119712844, 11149.609763658233, 11218.607348742755, 11278.266912650652, 395571.0, 396852.0, 400427.0, 511338.0, 523629.9755348304, 102280.48859755654, 102445.48777256066, 104935.47532262442, 105543.47228263809, 106283.0, 1079.09895237476, 1082.9729256768644, 1122.309779485627, 1125.0999200639455, 1127.6560649002133, 17743.201555929754, 17914.46256612304, 18012.36956706509, 18153.727694084337, 18284.085795710427, 44226.778866105466, 45000.0, 45793.08413831771, 46219.76890115528, 46236.0, 61514.0, 62999.6850015747, 64090.67954660197, 64562.354376457515, 64865.35134648593, 8900.109989001048, 9043.276537877086, 9101.590428430869, 9163.221126204347, 9491.620335186792, 10265.640702575374, 13346.332683365987, 13539.255340956342, 13553.525626603021, 13823.930880345937, 11649.708757281136, 11821.349825759666, 11908.345041022827, 11962.222455540363, 12144.574939877062, 11627.244229125079, 11726.296422214695, 11843.111766617365, 11894.405279735805, 12048.216865904038, 11650.592229271935, 11799.292042477478, 12035.157538972539, 12221.938890305491, 12450.377481126088, 8837.955810220908, 9056.0, 9173.99086100515, 9232.169104780602, 9243.2143267823, 9053.0, 11543.422828858691, 12223.44994475233, 12280.81578776337, 12289.262644241375], "value": 9549.319848902831, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.8810951069158, 1565.6633823727896, 1598.8880778345515, 1776.618027124168, 1939.3406241877758, 8627.65489380424, 9899.75250618735, 10153.593856245741, 10201.591936322548, 10204.336718113318, 9891.060349266827, 10101.34341267817, 10110.747231319221, 10112.646057387992, 10115.898841011587, 2511.6107003414454, 2547.4777670577487, 2551.1836212411945, 2552.247087109298, 2552.6553915221393, 2490.6139548370033, 2570.460203357288, 2572.9485410291877, 2573.2537564106387, 2574.3306740247485, 2461.7661322174454, 2465.149523414419, 2524.116559204282, 2526.1158594491963, 2527.4565968316806, 2397.125049356981, 2402.747711490303, 2409.5301416223842, 2447.1068060157913, 2458.483718419134, 2407.879606019692, 2426.8058555315533, 2433.0146290752095, 2437.9512409751705, 2448.326710154704, 9267.490288034156, 9509.714708558755, 9750.219982401391, 9934.90065099347, 9965.501724913725, 5825.126231065332, 5956.970215148896, 5961.314448838396, 5962.105684147369, 5965.4929331006915, 3462.8876385404883, 3514.38498262803, 3554.5379100717, 3563.8574457021687, 3611.0, 1148.0987424871437, 1152.1243854670427, 1153.81538953767, 1174.2602160638828, 1180.6103985684704, 13637.795433068512, 13715.245661488614, 13739.106958047794, 13742.244176570284, 13763.793543096861, 4933.753312334369, 4934.235193545006, 5014.824481143142, 5016.523430274118, 5050.84847454577, 10572.788544229072, 10638.989296016865, 10684.358938463716, 10693.304935179189, 10694.19793515474, 5662.2639056922335, 5669.0, 5727.026405511073, 5727.05503591911, 5732.914006289909, 4521.253993091103, 4592.931106033412, 5150.40770311419, 5318.654287471302, 5321.73391330432, 4201.831926722958, 4223.957760422418, 4294.720843145185, 4318.978405107954, 4341.848035318749, 1238.4427007846505, 1251.7559075980278, 1263.3493750718387, 1283.9101262911506, 1287.1118927939692, 969.1616751510035, 969.5733877094056, 979.4710856137748, 981.087588542648, 987.0376383026635, 5922.111683247547, 6017.759289628366, 6028.517718582503, 6046.969765151146, 6068.817935461943, 986.127277359542, 1001.1340190734962, 1033.3851358441716, 1048.2138396202847, 1051.9789604207872, 3921.627445392683, 3947.7631342119566, 4024.5371782245415, 4113.979430102891, 4124.649404800625, 12355.32045737493, 12593.622191334274, 12654.746905061847, 12694.682632934251, 12724.0, 12452.003839692985, 12474.75050498985, 12540.937295313464, 12551.497940082294, 12564.874351256552, 8858.335624828163, 9049.09509049103, 9063.13900179482, 9067.0, 9072.364934454525, 5873.500752436091, 5894.970525147347, 6040.426159514839, 6065.4533094060735, 6231.719572619334, 11101.611443599435, 11223.0, 11511.251768635348, 11556.248843825124, 11764.764704706029, 11716.94141529287, 11896.405179741152, 11989.040876729667, 12062.75874482488, 12201.816972745593, 7883.763487095396, 8559.443636163629, 8687.478751274944, 8798.560071996246, 8849.15932986391, 9741.902580974383, 9749.561269742737, 9761.75595610087, 9783.36408133469, 9842.458664773507, 8221.506709597443, 8264.256216940264, 8299.502029878227, 8327.167283271625, 8493.10822363643, 8126.379416618728, 8127.878081828659, 8579.571021449028, 8659.696910608098, 8660.39377243587, 7383.187849336461, 7620.31417172435, 7633.084029916224, 7757.146713861358, 7757.72847950319, 5103.974480127724, 5320.068987927175, 5366.973165134149, 5391.083515802245, 5465.234867118527, 5326.307580014573, 5326.3874654413685, 5382.111951527954, 5392.865178370572, 5500.944990550043], "value": 5006.312549661056, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.18285899578456, 364.4787953226884, 369.81139618794407, 372.16634738186434, 375.2663542773879, 5489.972550137254, 5511.173324001396, 5526.50261476467, 5543.556515478762, 5548.77804887805, 5251.842444726654, 5435.619506634537, 5491.720935811643, 5530.087535556639, 5564.888702225953, 4239.300515414953, 4240.194363071007, 4275.0594869128745, 4287.271163902128, 4293.248681480744, 4293.785310734466, 4307.504636966758, 4314.913701725964, 4315.633171180453, 4318.805653745589, 4216.789160541961, 4262.744235345889, 4273.487181538219, 4310.267254566716, 4310.56894310568, 4221.2190744712325, 4252.894247495673, 4263.019505513734, 4271.466066741657, 4288.0, 3785.9621403785886, 3791.7156213284115, 3801.6768574671187, 3831.540215174197, 3861.7682939023466, 5266.92099618506, 5292.920606190872, 5296.761645725952, 5297.125974214288, 5316.415194328621, 4846.176150054499, 4847.903041939177, 4927.384076990376, 4934.235193545006, 4948.183549714293, 5561.888762224733, 5566.972165139189, 5594.342687223577, 5610.7194640268235, 5650.180723795027, 4225.94351412147, 4231.957680423218, 4248.343146172964, 4249.086446414036, 4261.786910654455, 5630.380658127603, 5675.5743319250805, 5797.594168408255, 5834.5040671542965, 5892.528597712216, 4288.892777680583, 4298.914021719548, 4326.783660817009, 4341.891452713644, 4342.913141737147, 4318.0, 4335.263005289108, 4367.978160109179, 4409.162259170747, 4423.292273236267, 4705.482396936334, 4845.88544634736, 4895.388076490439, 4938.925916111261, 4996.800127994912, 5713.171785028834, 5730.8280751576685, 5740.368559458456, 5754.309482862083, 5775.306963164448, 1504.8720858727131, 1524.3902439024257, 1525.3136088760102, 1526.99236503819, 1531.540537838644, 977.53567055648, 1006.3157053203745, 1014.5333146752577, 1017.7099526634873, 1023.4933707814679, 5028.472010438852, 5033.446320904697, 5038.823641172541, 5114.41184263807, 5131.358580177478, 5196.9740151299, 5254.7898084076205, 5267.578593712494, 5306.063286900254, 5310.52205301517, 4734.95265047352, 4989.77954810106, 5013.699178049329, 5045.8738531537, 5059.494050594984, 2832.9008484702936, 2835.7873159512706, 2839.4463079699262, 2839.6024556562097, 2845.6585209774958, 5393.90060577191, 5403.432639572867, 5408.78364865401, 5438.851653596807, 5471.945280547223, 5128.410232823273, 5280.339957505312, 5336.879255356419, 5337.466253374709, 5346.331708536433, 5396.83809485716, 5444.673319600836, 5467.261919640798, 5491.615586908878, 5495.911553609602, 4819.145122096843, 5236.921446178234, 5256.369235691742, 5277.044854881279, 5322.228276899906, 5179.792808287626, 5231.215317702375, 5247.737613119405, 5254.369475662945, 5262.973720124539, 5931.288245410406, 6044.274687037408, 6162.691865406622, 6219.689015549295, 6240.4071613196675, 4343.630791382641, 4345.2395830728865, 4367.781610919505, 4379.5401482843845, 4380.145871555016, 5741.0, 5944.821655350346, 5957.314908785372, 5984.341722410619, 5999.640021598718, 5708.714564271687, 5742.684152371493, 5772.0, 5792.160136780059, 5805.245318108618, 5692.743826527733, 5742.966266072147, 5743.971280143573, 5754.395788442153, 5807.94192058074, 5494.203340515683, 5696.857578560569, 5770.163326317745, 5807.738651760596, 5822.679752613478, 5870.765169393177, 5886.852828679317, 5888.4700376966275, 5912.852178695395, 5940.079287710412, 5575.4424557543925, 5754.769809207585, 5802.216700745345, 5835.862006908612, 5844.444777746107], "value": 4305.425232029706, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.5082569265744, 746.0525133080989, 760.1790066727934, 775.9806004849878, 780.1145699630919, 14010.859891401084, 15407.922960385196, 15581.376744930189, 15724.292406841705, 15735.055896646181, 14426.567202983928, 14589.92705036476, 14593.489227877026, 14600.707985840278, 14609.707805843904, 707.4446559450845, 835.9665613375457, 865.8051938313861, 866.0862789756804, 870.7170169694842, 835.0438747633956, 842.3093063687778, 843.2158092973532, 843.5065486690294, 845.3237410071948, 745.3589912675095, 926.7127190570911, 927.7448701607078, 928.9163975242227, 929.7443203119162, 878.9384743067988, 916.5783739479847, 918.1919910478736, 918.4489306416164, 919.8850143732034, 673.498243808361, 673.9898901516432, 675.5136301862651, 676.4791110844623, 676.671814170127, 14102.365393557315, 14140.080894741808, 14144.575662730134, 14152.150870947673, 14157.362918668685, 14994.250287485582, 15111.697766044617, 15335.769963450446, 15339.386424543005, 15385.923070384688, 13117.934410327982, 13319.53381631648, 13332.933335333359, 13356.933215333862, 13538.59384218475, 4589.403377560929, 4640.257558790611, 4653.581177694024, 4681.555252251031, 4699.342092107074, 75983.0, 77199.61400192963, 77295.0, 78503.0, 78839.21160788376, 12349.876501234872, 12364.319962402154, 12571.120021598583, 12622.179558328678, 12846.421911014028, 22329.218477353214, 22474.550508989727, 22486.100555977577, 22495.550088998127, 22547.436314091952, 8894.688685895962, 8962.641494340283, 9038.09619038104, 9105.317101217302, 9134.680286189952, 5060.898782024339, 5154.329937108152, 5168.457311982263, 5242.633015688865, 5271.65734227274, 3698.5561732591723, 3789.374753165697, 3814.332491813977, 3817.961820381761, 3818.4463252828186, 649.243631169682, 681.9795406137824, 701.1095908196562, 736.4550232827669, 750.7447467860953, 576.685706290073, 613.2763339259627, 613.4969325153421, 629.8079085878851, 702.1363722621204, 8930.240929520933, 9059.954700226588, 9113.544322783968, 9272.7681807954, 9702.369345992487, 593.9227900372924, 621.0466933257518, 641.4964253061324, 691.509028589707, 705.7529864547367, 4484.0, 4667.533246675373, 4736.881577960578, 4812.374391329105, 4849.345338379274, 83476.0, 98551.0, 112023.43988280007, 115243.0, 115273.0, 65185.348146518874, 92886.0, 94164.0, 101531.0, 104393.47803260935, 77380.22619773843, 88053.55973220093, 88238.55880720555, 88467.0, 90215.0, 9572.56923438461, 9866.358686685342, 9868.901310987085, 10042.246831487813, 10187.541560629641, 40022.799886000386, 44236.11527769491, 45136.54863451324, 48585.51414485951, 52724.472755271956, 74186.62906685432, 75716.62141689476, 75825.6208718975, 75955.0, 77262.0, 12593.93703031479, 12837.743245134858, 14148.646283842987, 14257.215853128178, 14695.3387097583, 15493.612659683598, 16004.519864404087, 16083.195840208176, 16119.838801611833, 16292.592685182964, 14557.92721036388, 14582.927085364505, 14681.0, 14710.77933830972, 15216.163111029, 15251.771223431435, 15302.0, 15407.38370465169, 15963.361465541248, 16284.51146465608, 14768.409263629335, 15240.0, 15257.6185595361, 15497.612559685647, 15739.921300393426, 12300.261984280973, 12404.0, 12817.295048772407, 12985.675358116123, 13076.346182691017, 12292.81560776607, 12486.250824950532, 12492.0, 12758.48966041348, 12878.742425151631], "value": 7830.583032059749, "unit": "score"}], "name": "dromaeo_css", "value": 7590.601445305556, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 109.0, 110.0, 112.0, 111.0, 113.0, 110.0, 109.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 123.0, 128.0, 187.0, 127.0, 125.0, 151.0, 125.0, 199.0, 126.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 159.0, 161.0, 160.0, 160.0, 160.0, 159.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 125.0, 75.0, 75.0, 76.0, 74.0, 103.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 115.0, 85.0, 86.0, 85.0, 123.0, 84.0, 85.0, 124.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 145.0, 145.0, 147.0, 145.0, 146.0, 145.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 51.0, 51.0, 53.0, 54.0, 53.0, 52.0, 52.0, 52.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 81.0, 83.0, 81.0, 82.0, 81.0, 77.0, 140.0, 76.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 123.0, 134.0, 117.0, 119.0, 125.0, 133.0, 132.0, 134.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 155.0, 154.0, 153.0, 154.0, 152.0, 154.0, 151.0, 151.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 62.0, 63.0, 65.0, 62.0, 61.0, 60.0, 62.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1495.3000000000002, "alertThreshold": 2.0}]} 04:14:26 INFO - Return code: 0 04:14:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:14:26 ERROR - # TBPL SUCCESS # 04:14:26 INFO - Running post-action listener: _resource_record_post_action 04:14:26 INFO - ##### 04:14:26 INFO - ##### Finished run-tests step (success) 04:14:26 INFO - ##### 04:14:26 INFO - Running post-run listener: _resource_record_post_run 04:14:26 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 3338240; Write bytes: 286384128; Read time: 428; Write time: 140128 04:14:26 INFO - TinderboxPrint: CPU usage
13.1% 04:14:26 INFO - TinderboxPrint: I/O read bytes / time
3,338,240 / 428 04:14:26 INFO - TinderboxPrint: I/O write bytes / time
286,384,128 / 140,128 04:14:26 INFO - TinderboxPrint: CPU idle
8,240.7 (86.9%) 04:14:26 INFO - TinderboxPrint: CPU user
1,199.3 (12.6%) 04:14:26 INFO - TinderboxPrint: Swap in / out
0 / 0 04:14:26 INFO - install - Wall time: 13s; CPU: 13.0%; Read bytes: 4096; Write bytes: 68636672; Read time: 200; Write time: 34656 04:14:26 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 3309568; Write bytes: 217354240; Read time: 220; Write time: 105432 04:14:27 INFO - Running post-run listener: _upload_blobber_files 04:14:27 INFO - Blob upload gear active. 04:14:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:14:27 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 04:14:27 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'] 04:14:27 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 04:14:27 INFO - Traceback (most recent call last): 04:14:27 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 04:14:27 INFO - main() 04:14:27 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 04:14:27 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 04:14:27 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 04:14:27 INFO - return set(response.json().get('whitelist', [])) 04:14:27 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 04:14:27 INFO - return json.loads(self.text or self.content, **kwargs) 04:14:27 INFO - File "/usr/lib/python2.7/json/__init__.py", line 326, in loads 04:14:27 INFO - return _default_decoder.decode(s) 04:14:27 INFO - File "/usr/lib/python2.7/json/decoder.py", line 366, in decode 04:14:27 INFO - obj, end = self.raw_decode(s, idx=_w(s, 0).end()) 04:14:27 INFO - File "/usr/lib/python2.7/json/decoder.py", line 384, in raw_decode 04:14:27 INFO - raise ValueError("No JSON object could be decoded") 04:14:27 INFO - ValueError: No JSON object could be decoded 04:14:27 ERROR - Return code: 1 04:14:27 INFO - Setting buildbot property blobber_files to {} 04:14:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:14:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:14:27 INFO - Contents: 04:14:27 INFO - blobber_files:{} 04:14:27 INFO - Running post-run listener: copy_logs_to_upload_dir 04:14:27 INFO - Copying logs to upload dir... 04:14:27 INFO - mkdir: /builds/slave/test/build/upload/logs 04:14:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1672.330452 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-08-28 04:14:27.768226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-28 04:14:27.776054) ========= 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=d2a8c569965ab8367867b893000001a1-1472381193.781026-619647704 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011160 build_url: 'https://queue.taskcluster.net/v1/task/GdlaXD_eR6Kz1T8VLocPNQ/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-28 04:14:27.831437) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:14:27.831781) ========= 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=d2a8c569965ab8367867b893000001a1-1472381193.781026-619647704 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:14:27.881329) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-28 04:14:27.881625) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-28 04:14:27.884938) ========= ========= Total master_lag: 0.18 =========