builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1463649586.59 results: success (0) buildid: 20160517065136 builduid: ab760662d3a1428fa4c9e673ad9c7baf revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.588269) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.588724) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.589004) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.606604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.606955) ========= 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=0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 02:19:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-05-19 02:19:47 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141094 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.761534) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.761850) ========= 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=0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017317 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.811723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:46.812151) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --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=0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 02:19:47,169 truncating revision to first 12 chars 2016-05-19 02:19:47,169 Setting DEBUG logging. 2016-05-19 02:19:47,169 attempt 1/10 2016-05-19 02:19:47,169 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3780a3a6b83a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 02:19:47,844 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.953896 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:47.778679) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:47.779043) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:47.779438) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:47.779721) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 02:19:47.788501) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-05-19 02:19:47.788847) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:19:48 INFO - MultiFileLogger online at 20160519 02:19:48 in /builds/slave/test 02:19:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:19:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:19:48 INFO - {'append_to_log': False, 02:19:48 INFO - 'base_work_dir': '/builds/slave/test', 02:19:48 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:19:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:19:48 INFO - 'branch': 'Firefox-Non-PGO', 02:19:48 INFO - 'buildbot_json_path': 'buildprops.json', 02:19:48 INFO - 'config_files': ('talos/linux_config.py',), 02:19:48 INFO - 'default_actions': ('clobber', 02:19:48 INFO - 'read-buildbot-config', 02:19:48 INFO - 'download-and-extract', 02:19:48 INFO - 'populate-webroot', 02:19:48 INFO - 'create-virtualenv', 02:19:48 INFO - 'install', 02:19:48 INFO - 'run-tests'), 02:19:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:19:48 INFO - 'download_minidump_stackwalk': True, 02:19:48 INFO - 'download_symbols': 'ondemand', 02:19:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:19:48 INFO - 'tooltool.py': '/tools/tooltool.py', 02:19:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:19:48 INFO - '/tools/misc-python/virtualenv.py')}, 02:19:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:19:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:19:48 INFO - 'installer_path': 'installer.exe', 02:19:48 INFO - 'log_level': 'info', 02:19:48 INFO - 'log_name': 'talos', 02:19:48 INFO - 'log_to_console': True, 02:19:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:19:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:19:48 INFO - 'opt_config_files': (), 02:19:48 INFO - 'pip_index': False, 02:19:48 INFO - 'sps_profile': False, 02:19:48 INFO - 'sps_profile_interval': 0, 02:19:48 INFO - 'suite': 'dromaeojs', 02:19:48 INFO - 'system_bits': '32', 02:19:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:19:48 INFO - 'title': 'talos-linux64-ix-017', 02:19:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:19:48 INFO - 'use_talos_json': True, 02:19:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:19:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:19:48 INFO - 'work_dir': 'build'} 02:19:48 INFO - ##### 02:19:48 INFO - ##### Running clobber step. 02:19:48 INFO - ##### 02:19:48 INFO - Running pre-action listener: _resource_record_pre_action 02:19:48 INFO - Running main action method: clobber 02:19:48 INFO - rmtree: /builds/slave/test/build 02:19:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:19:48 INFO - Running post-action listener: _resource_record_post_action 02:19:48 INFO - ##### 02:19:48 INFO - ##### Finished clobber step (success) 02:19:48 INFO - ##### 02:19:48 INFO - ##### 02:19:48 INFO - ##### Running read-buildbot-config step. 02:19:48 INFO - ##### 02:19:48 INFO - Running pre-action listener: _resource_record_pre_action 02:19:48 INFO - Running main action method: read_buildbot_config 02:19:48 INFO - Using buildbot properties: 02:19:48 INFO - { 02:19:48 INFO - "project": "", 02:19:48 INFO - "product": "firefox", 02:19:48 INFO - "script_repo_revision": "production", 02:19:48 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 02:19:48 INFO - "repository": "", 02:19:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 02:19:48 INFO - "buildid": "20160517065136", 02:19:48 INFO - "pgo_build": "False", 02:19:48 INFO - "basedir": "/builds/slave/test", 02:19:48 INFO - "buildnumber": 86, 02:19:48 INFO - "slavename": "talos-linux64-ix-017", 02:19:48 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:19:48 INFO - "platform": "ubuntu64_hw", 02:19:48 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 02:19:48 INFO - "branch": "mozilla-central", 02:19:48 INFO - "repo_path": "mozilla-central", 02:19:48 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 02:19:48 INFO - "stage_platform": "linux64", 02:19:48 INFO - "builduid": "ab760662d3a1428fa4c9e673ad9c7baf", 02:19:48 INFO - "slavebuilddir": "test" 02:19:48 INFO - } 02:19:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 02:19:48 INFO - Running post-action listener: _resource_record_post_action 02:19:48 INFO - ##### 02:19:48 INFO - ##### Finished read-buildbot-config step (success) 02:19:48 INFO - ##### 02:19:48 INFO - ##### 02:19:48 INFO - ##### Running download-and-extract step. 02:19:48 INFO - ##### 02:19:48 INFO - Running pre-action listener: _resource_record_pre_action 02:19:48 INFO - Running main action method: download_and_extract 02:19:48 INFO - mkdir: /builds/slave/test/build/tests 02:19:48 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')]} 02:19:48 INFO - https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:19:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:19:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:19:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:19:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:19:49 INFO - Downloaded 1361 bytes. 02:19:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:19:49 INFO - Using the following test package requirements: 02:19:49 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:19:49 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:19:49 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:19:49 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'jsshell-linux-x86_64.zip'], 02:19:49 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:19:49 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:19:49 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:19:49 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:19:49 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:19:49 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:19:49 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:19:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:19:49 INFO - https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:19:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:19:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:19:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:19:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:19:50 INFO - Downloaded 22447955 bytes. 02:19:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:19:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:19:51 INFO - Return code: 0 02:19:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:19:51 INFO - https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:19:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:19:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:19:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:19:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:19:52 INFO - Downloaded 22447955 bytes. 02:19:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:19:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:19:54 INFO - Return code: 0 02:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:19:54 INFO - https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:19:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:19:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:19:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:19:56 INFO - Downloaded 10840194 bytes. 02:19:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:19:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:19:56 INFO - Return code: 0 02:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:19:56 INFO - https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:19:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:19:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:19:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:19:59 INFO - Downloaded 54754715 bytes. 02:19:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:19:59 INFO - mkdir: /builds/slave/test/properties 02:19:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:19:59 INFO - Writing to file /builds/slave/test/properties/build_url 02:19:59 INFO - Contents: 02:19:59 INFO - build_url:https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:20:00 INFO - Running post-action listener: _resource_record_post_action 02:20:00 INFO - Running post-action listener: set_extra_try_arguments 02:20:00 INFO - ##### 02:20:00 INFO - ##### Finished download-and-extract step (success) 02:20:00 INFO - ##### 02:20:00 INFO - ##### 02:20:00 INFO - ##### Running populate-webroot step. 02:20:00 INFO - ##### 02:20:00 INFO - Running pre-action listener: _resource_record_pre_action 02:20:00 INFO - Running main action method: populate_webroot 02:20:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:20:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:20:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:20:00 INFO - '--tppagecycles', 02:20:00 INFO - '7'], 02:20:00 INFO - 'tests': ['tsvgm']}}, 02:20:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:20:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:20:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:20:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:20:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:20:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:20:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['damp', 'tps']}, 02:20:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'tests': ['damp', 'tps']}, 02:20:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['dromaeo_dom']}, 02:20:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:20:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['a11yr', 02:20:00 INFO - 'ts_paint', 02:20:00 INFO - 'tpaint', 02:20:00 INFO - 'sessionrestore', 02:20:00 INFO - 'sessionrestore_no_auto_restore', 02:20:00 INFO - 'tabpaint']}, 02:20:00 INFO - 'other-e10s': {'tests': ['a11yr', 02:20:00 INFO - 'ts_paint', 02:20:00 INFO - 'tpaint', 02:20:00 INFO - 'sessionrestore', 02:20:00 INFO - 'sessionrestore_no_auto_restore', 02:20:00 INFO - 'tabpaint']}, 02:20:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:20:00 INFO - 'ts_paint', 02:20:00 INFO - 'tpaint', 02:20:00 INFO - 'sessionrestore', 02:20:00 INFO - 'sessionrestore_no_auto_restore', 02:20:00 INFO - 'tabpaint']}, 02:20:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:20:00 INFO - 'ts_paint', 02:20:00 INFO - 'tpaint', 02:20:00 INFO - 'sessionrestore', 02:20:00 INFO - 'sessionrestore_no_auto_restore', 02:20:00 INFO - 'tabpaint']}, 02:20:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['a11yr', 02:20:00 INFO - 'ts_paint', 02:20:00 INFO - 'tpaint', 02:20:00 INFO - 'sessionrestore', 02:20:00 INFO - 'sessionrestore_no_auto_restore', 02:20:00 INFO - 'tabpaint']}, 02:20:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['a11yr', 02:20:00 INFO - 'ts_paint', 02:20:00 INFO - 'tpaint', 02:20:00 INFO - 'sessionrestore', 02:20:00 INFO - 'sessionrestore_no_auto_restore', 02:20:00 INFO - 'tabpaint']}, 02:20:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['tsvgx', 02:20:00 INFO - 'tsvgr_opacity', 02:20:00 INFO - 'tart', 02:20:00 INFO - 'tscrollx', 02:20:00 INFO - 'cart']}, 02:20:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:20:00 INFO - 'tsvgr_opacity', 02:20:00 INFO - 'tart', 02:20:00 INFO - 'tscrollx', 02:20:00 INFO - 'cart']}, 02:20:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'talos_options': ['--disable-e10s'], 02:20:00 INFO - 'tests': ['tp5o']}, 02:20:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'tests': ['tp5o']}, 02:20:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'talos_options': ['--disable-e10s', 02:20:00 INFO - '--xperf_path', 02:20:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:20:00 INFO - 'tests': ['tp5n']}, 02:20:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:20:00 INFO - 'talos_options': ['--xperf_path', 02:20:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:20:00 INFO - 'tests': ['tp5n']}}, 02:20:00 INFO - 'talos.zip': {'path': '', 02:20:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:20:00 INFO - Running post-action listener: _resource_record_post_action 02:20:00 INFO - ##### 02:20:00 INFO - ##### Finished populate-webroot step (success) 02:20:00 INFO - ##### 02:20:00 INFO - ##### 02:20:00 INFO - ##### Running create-virtualenv step. 02:20:00 INFO - ##### 02:20:00 INFO - Running pre-action listener: _resource_record_pre_action 02:20:00 INFO - Running main action method: create_virtualenv 02:20:00 INFO - Creating virtualenv /builds/slave/test/build/venv 02:20:00 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 02:20:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:20:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:20:00 INFO - Using real prefix '/usr' 02:20:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:20:00 INFO - Installing distribute.............................................................................................................................................................................................done. 02:20:02 INFO - Installing pip.................done. 02:20:02 INFO - Return code: 0 02:20:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:02 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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:02 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 02:20:02 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 02:20:02 INFO - Using env: {'DISPLAY': ':0', 02:20:02 INFO - 'HOME': '/home/cltbld', 02:20:02 INFO - 'LANG': 'en_US.UTF-8', 02:20:02 INFO - 'LANGUAGE': 'en_US:en', 02:20:02 INFO - 'LOGNAME': 'cltbld', 02:20:02 INFO - 'MAIL': '/var/mail/cltbld', 02:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:02 INFO - 'MOZ_NO_REMOTE': '1', 02:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:02 INFO - 'NO_EM_RESTART': '1', 02:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:02 INFO - 'PWD': '/builds/slave/test', 02:20:02 INFO - 'SHELL': '/bin/bash', 02:20:02 INFO - 'SHLVL': '1', 02:20:02 INFO - 'TERM': 'linux', 02:20:02 INFO - 'TMOUT': '86400', 02:20:02 INFO - 'USER': 'cltbld', 02:20:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:02 INFO - '_': '/tools/buildbot/bin/python'} 02:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:03 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 02:20:03 INFO - Cleaning up... 02:20:03 INFO - Return code: 0 02:20:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:20:03 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')]} 02:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:03 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')]} 02:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:03 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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:03 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 02:20:03 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 02:20:03 INFO - Using env: {'DISPLAY': ':0', 02:20:03 INFO - 'HOME': '/home/cltbld', 02:20:03 INFO - 'LANG': 'en_US.UTF-8', 02:20:03 INFO - 'LANGUAGE': 'en_US:en', 02:20:03 INFO - 'LOGNAME': 'cltbld', 02:20:03 INFO - 'MAIL': '/var/mail/cltbld', 02:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:03 INFO - 'MOZ_NO_REMOTE': '1', 02:20:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:03 INFO - 'NO_EM_RESTART': '1', 02:20:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:03 INFO - 'PWD': '/builds/slave/test', 02:20:03 INFO - 'SHELL': '/bin/bash', 02:20:03 INFO - 'SHLVL': '1', 02:20:03 INFO - 'TERM': 'linux', 02:20:03 INFO - 'TMOUT': '86400', 02:20:03 INFO - 'USER': 'cltbld', 02:20:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:03 INFO - '_': '/tools/buildbot/bin/python'} 02:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:03 INFO - Downloading/unpacking psutil>=3.1.1 02:20:03 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 02:20:03 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 02:20:03 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 02:20:03 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 02:20:03 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 02:20:03 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 02:20:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:20:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:20:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:20:05 INFO - Installing collected packages: psutil 02:20:05 INFO - Running setup.py install for psutil 02:20:05 INFO - building 'psutil._psutil_linux' extension 02:20:05 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 02:20:05 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 02:20:05 INFO - building 'psutil._psutil_posix' extension 02:20:05 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 02:20:05 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 02:20:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:20:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:20:05 INFO - Successfully installed psutil 02:20:05 INFO - Cleaning up... 02:20:05 INFO - Return code: 0 02:20:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:20:05 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')]} 02:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:05 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')]} 02:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:05 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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:05 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 02:20:05 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 02:20:05 INFO - Using env: {'DISPLAY': ':0', 02:20:05 INFO - 'HOME': '/home/cltbld', 02:20:05 INFO - 'LANG': 'en_US.UTF-8', 02:20:05 INFO - 'LANGUAGE': 'en_US:en', 02:20:05 INFO - 'LOGNAME': 'cltbld', 02:20:05 INFO - 'MAIL': '/var/mail/cltbld', 02:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:05 INFO - 'MOZ_NO_REMOTE': '1', 02:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:05 INFO - 'NO_EM_RESTART': '1', 02:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:05 INFO - 'PWD': '/builds/slave/test', 02:20:05 INFO - 'SHELL': '/bin/bash', 02:20:05 INFO - 'SHLVL': '1', 02:20:05 INFO - 'TERM': 'linux', 02:20:05 INFO - 'TMOUT': '86400', 02:20:05 INFO - 'USER': 'cltbld', 02:20:05 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:05 INFO - '_': '/tools/buildbot/bin/python'} 02:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:20:06 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 02:20:06 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 02:20:06 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 02:20:06 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 02:20:06 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 02:20:06 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 02:20:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:20:08 INFO - Installing collected packages: mozsystemmonitor 02:20:08 INFO - Running setup.py install for mozsystemmonitor 02:20:08 INFO - Successfully installed mozsystemmonitor 02:20:08 INFO - Cleaning up... 02:20:08 INFO - Return code: 0 02:20:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:20:08 INFO - Using env: {'DISPLAY': ':0', 02:20:08 INFO - 'HOME': '/home/cltbld', 02:20:08 INFO - 'LANG': 'en_US.UTF-8', 02:20:08 INFO - 'LANGUAGE': 'en_US:en', 02:20:08 INFO - 'LOGNAME': 'cltbld', 02:20:08 INFO - 'MAIL': '/var/mail/cltbld', 02:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:08 INFO - 'MOZ_NO_REMOTE': '1', 02:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:08 INFO - 'NO_EM_RESTART': '1', 02:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:08 INFO - 'PWD': '/builds/slave/test', 02:20:08 INFO - 'SHELL': '/bin/bash', 02:20:08 INFO - 'SHLVL': '1', 02:20:08 INFO - 'TERM': 'linux', 02:20:08 INFO - 'TMOUT': '86400', 02:20:08 INFO - 'USER': 'cltbld', 02:20:08 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:08 INFO - '_': '/tools/buildbot/bin/python'} 02:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:08 INFO - Downloading/unpacking blobuploader==1.2.4 02:20:08 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 02:20:08 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 02:20:08 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 02:20:08 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 02:20:08 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 02:20:08 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 02:20:10 INFO - Downloading blobuploader-1.2.4.tar.gz 02:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:20:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:20:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:10 INFO - Downloading docopt-0.6.1.tar.gz 02:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:20:11 INFO - Installing collected packages: blobuploader, requests, docopt 02:20:11 INFO - Running setup.py install for blobuploader 02:20:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:20:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:20:11 INFO - Running setup.py install for requests 02:20:11 INFO - Running setup.py install for docopt 02:20:11 INFO - Successfully installed blobuploader requests docopt 02:20:11 INFO - Cleaning up... 02:20:11 INFO - Return code: 0 02:20:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:20: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')]} 02:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20: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')]} 02:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:11 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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:11 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 02:20:11 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 02:20:11 INFO - Using env: {'DISPLAY': ':0', 02:20:11 INFO - 'HOME': '/home/cltbld', 02:20:11 INFO - 'LANG': 'en_US.UTF-8', 02:20:11 INFO - 'LANGUAGE': 'en_US:en', 02:20:11 INFO - 'LOGNAME': 'cltbld', 02:20:11 INFO - 'MAIL': '/var/mail/cltbld', 02:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:11 INFO - 'MOZ_NO_REMOTE': '1', 02:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:11 INFO - 'NO_EM_RESTART': '1', 02:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:11 INFO - 'PWD': '/builds/slave/test', 02:20:11 INFO - 'SHELL': '/bin/bash', 02:20:11 INFO - 'SHLVL': '1', 02:20:11 INFO - 'TERM': 'linux', 02:20:11 INFO - 'TMOUT': '86400', 02:20:11 INFO - 'USER': 'cltbld', 02:20:11 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:11 INFO - '_': '/tools/buildbot/bin/python'} 02:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:20:11 INFO - Running setup.py (path:/tmp/pip-DDGXxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:20:11 INFO - Running setup.py (path:/tmp/pip-Rtkl3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:20:12 INFO - Running setup.py (path:/tmp/pip-cK3haA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:20:12 INFO - Running setup.py (path:/tmp/pip-yObG60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:20:12 INFO - Running setup.py (path:/tmp/pip-JVSYMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:20:12 INFO - Running setup.py (path:/tmp/pip-NnGDsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:20:12 INFO - Running setup.py (path:/tmp/pip-TPfBlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:20:12 INFO - Running setup.py (path:/tmp/pip-dvmlqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:20:12 INFO - Running setup.py (path:/tmp/pip-vUvG4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:20:12 INFO - Running setup.py (path:/tmp/pip-KieoMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:20:12 INFO - Running setup.py (path:/tmp/pip-T6NswV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:20:12 INFO - Running setup.py (path:/tmp/pip-iKF5Au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:20:12 INFO - Running setup.py (path:/tmp/pip-UBDK8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:20:12 INFO - Running setup.py (path:/tmp/pip-ywXkvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:20:12 INFO - Running setup.py (path:/tmp/pip-0YRcPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:20:12 INFO - Running setup.py (path:/tmp/pip-6hMkAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:20:13 INFO - Running setup.py (path:/tmp/pip-Ca4NrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:20:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:20:13 INFO - Running setup.py install for manifestparser 02:20:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Running setup.py install for mozcrash 02:20:13 INFO - Running setup.py install for mozdebug 02:20:13 INFO - Running setup.py install for mozdevice 02:20:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Running setup.py install for mozfile 02:20:13 INFO - Running setup.py install for mozhttpd 02:20:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Running setup.py install for mozinfo 02:20:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Running setup.py install for mozInstall 02:20:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:20:13 INFO - Running setup.py install for mozleak 02:20:14 INFO - Running setup.py install for mozlog 02:20:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Running setup.py install for moznetwork 02:20:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Running setup.py install for mozprocess 02:20:14 INFO - Running setup.py install for mozprofile 02:20:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Running setup.py install for mozrunner 02:20:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Running setup.py install for mozscreenshot 02:20:14 INFO - Running setup.py install for moztest 02:20:14 INFO - Running setup.py install for mozversion 02:20:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:20:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:20:14 INFO - Cleaning up... 02:20:14 INFO - Return code: 0 02:20:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:20:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:14 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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:14 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 02:20:14 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 02:20:14 INFO - Using env: {'DISPLAY': ':0', 02:20:14 INFO - 'HOME': '/home/cltbld', 02:20:14 INFO - 'LANG': 'en_US.UTF-8', 02:20:14 INFO - 'LANGUAGE': 'en_US:en', 02:20:14 INFO - 'LOGNAME': 'cltbld', 02:20:14 INFO - 'MAIL': '/var/mail/cltbld', 02:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:14 INFO - 'MOZ_NO_REMOTE': '1', 02:20:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:14 INFO - 'NO_EM_RESTART': '1', 02:20:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:14 INFO - 'PWD': '/builds/slave/test', 02:20:14 INFO - 'SHELL': '/bin/bash', 02:20:14 INFO - 'SHLVL': '1', 02:20:14 INFO - 'TERM': 'linux', 02:20:14 INFO - 'TMOUT': '86400', 02:20:14 INFO - 'USER': 'cltbld', 02:20:14 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:14 INFO - '_': '/tools/buildbot/bin/python'} 02:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:20:15 INFO - Running setup.py (path:/tmp/pip-jRUBrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:20:15 INFO - Running setup.py (path:/tmp/pip-CdCVs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:20:15 INFO - Running setup.py (path:/tmp/pip-7QYiuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:20:15 INFO - Running setup.py (path:/tmp/pip-h923IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:20:15 INFO - Running setup.py (path:/tmp/pip-V3ODdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:20:15 INFO - Running setup.py (path:/tmp/pip-szyIXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:20:15 INFO - Running setup.py (path:/tmp/pip-tCDQu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:20:15 INFO - Running setup.py (path:/tmp/pip-XP6jQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:20:15 INFO - Running setup.py (path:/tmp/pip-FNnxAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:20:15 INFO - Running setup.py (path:/tmp/pip-sDkDww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:20:15 INFO - Running setup.py (path:/tmp/pip-PA_D5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:20:15 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)) 02:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:20:15 INFO - Running setup.py (path:/tmp/pip-d172sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:20:16 INFO - Running setup.py (path:/tmp/pip-Vvx1cO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:20:16 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)) 02:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:20:16 INFO - Running setup.py (path:/tmp/pip-uQMPYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:20:16 INFO - Running setup.py (path:/tmp/pip-k1z7yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:20:16 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)) 02:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:20:16 INFO - Running setup.py (path:/tmp/pip-o7JXnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:20:16 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)) 02:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:20:16 INFO - Running setup.py (path:/tmp/pip-StZ6hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:20:16 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)) 02:20:16 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)) 02:20:16 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)) 02:20:16 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)) 02:20:16 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)) 02:20:16 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)) 02:20:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:18 INFO - Downloading blessings-1.6.tar.gz 02:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:20:18 INFO - Installing collected packages: blessings 02:20:18 INFO - Running setup.py install for blessings 02:20:18 INFO - Successfully installed blessings 02:20:18 INFO - Cleaning up... 02:20:18 INFO - Return code: 0 02:20:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:20:18 INFO - Reading from file tmpfile_stdout 02:20:18 INFO - Current package versions: 02:20:18 INFO - argparse == 1.2.1 02:20:18 INFO - blessings == 1.6 02:20:18 INFO - blobuploader == 1.2.4 02:20:18 INFO - docopt == 0.6.1 02:20:18 INFO - manifestparser == 1.1 02:20:18 INFO - mozInstall == 1.12 02:20:18 INFO - mozcrash == 0.17 02:20:18 INFO - mozdebug == 0.1 02:20:18 INFO - mozdevice == 0.48 02:20:18 INFO - mozfile == 1.2 02:20:18 INFO - mozhttpd == 0.7 02:20:18 INFO - mozinfo == 0.9 02:20:18 INFO - mozleak == 0.1 02:20:18 INFO - mozlog == 3.2 02:20:18 INFO - moznetwork == 0.27 02:20:18 INFO - mozprocess == 0.22 02:20:18 INFO - mozprofile == 0.28 02:20:18 INFO - mozrunner == 6.11 02:20:18 INFO - mozscreenshot == 0.1 02:20:18 INFO - mozsystemmonitor == 0.3 02:20:18 INFO - moztest == 0.7 02:20:18 INFO - mozversion == 1.4 02:20:18 INFO - psutil == 3.1.1 02:20:18 INFO - requests == 1.2.3 02:20:18 INFO - wsgiref == 0.1.2 02:20:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:18 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 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:18 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 02:20:18 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 02:20:18 INFO - Using env: {'DISPLAY': ':0', 02:20:18 INFO - 'HOME': '/home/cltbld', 02:20:18 INFO - 'LANG': 'en_US.UTF-8', 02:20:18 INFO - 'LANGUAGE': 'en_US:en', 02:20:18 INFO - 'LOGNAME': 'cltbld', 02:20:18 INFO - 'MAIL': '/var/mail/cltbld', 02:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:18 INFO - 'MOZ_NO_REMOTE': '1', 02:20:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:18 INFO - 'NO_EM_RESTART': '1', 02:20:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:18 INFO - 'PWD': '/builds/slave/test', 02:20:18 INFO - 'SHELL': '/bin/bash', 02:20:18 INFO - 'SHLVL': '1', 02:20:18 INFO - 'TERM': 'linux', 02:20:18 INFO - 'TMOUT': '86400', 02:20:18 INFO - 'USER': 'cltbld', 02:20:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:18 INFO - '_': '/tools/buildbot/bin/python'} 02:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 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)) 02:20:18 INFO - Cleaning up... 02:20:18 INFO - Return code: 0 02:20:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:20:18 INFO - Using env: {'DISPLAY': ':0', 02:20:18 INFO - 'HOME': '/home/cltbld', 02:20:18 INFO - 'LANG': 'en_US.UTF-8', 02:20:18 INFO - 'LANGUAGE': 'en_US:en', 02:20:18 INFO - 'LOGNAME': 'cltbld', 02:20:18 INFO - 'MAIL': '/var/mail/cltbld', 02:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:18 INFO - 'MOZ_NO_REMOTE': '1', 02:20:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:18 INFO - 'NO_EM_RESTART': '1', 02:20:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:18 INFO - 'PWD': '/builds/slave/test', 02:20:18 INFO - 'SHELL': '/bin/bash', 02:20:18 INFO - 'SHLVL': '1', 02:20:18 INFO - 'TERM': 'linux', 02:20:18 INFO - 'TMOUT': '86400', 02:20:18 INFO - 'USER': 'cltbld', 02:20:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:18 INFO - '_': '/tools/buildbot/bin/python'} 02:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:19 INFO - Downloading/unpacking jsonschema 02:20: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 02:20: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 02:20: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 02:20: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 02:20: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 02:20: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 02:20:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:20:20 INFO - Downloading/unpacking functools32 (from jsonschema) 02:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:21 INFO - Downloading functools32-3.2.3-2.tar.gz 02:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:20:21 INFO - warning: no files found matching '*.txt' 02:20:21 INFO - no previously-included directories found matching 'build' 02:20:21 INFO - no previously-included directories found matching 'dist' 02:20:21 INFO - no previously-included directories found matching '.git*' 02:20:21 INFO - Installing collected packages: jsonschema, functools32 02:20:21 INFO - Running setup.py install for functools32 02:20:21 INFO - warning: no files found matching '*.txt' 02:20:21 INFO - no previously-included directories found matching 'build' 02:20:21 INFO - no previously-included directories found matching 'dist' 02:20:21 INFO - no previously-included directories found matching '.git*' 02:20:21 INFO - Successfully installed jsonschema functools32 02:20:21 INFO - Cleaning up... 02:20:21 INFO - Return code: 0 02:20:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:20: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')]} 02:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20: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')]} 02:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:20: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f125c703f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2175770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, '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': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', '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 02:20: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', 'flake8'] in /builds/slave/test/build 02:20: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 flake8 02:20:21 INFO - Using env: {'DISPLAY': ':0', 02:20:21 INFO - 'HOME': '/home/cltbld', 02:20:21 INFO - 'LANG': 'en_US.UTF-8', 02:20:21 INFO - 'LANGUAGE': 'en_US:en', 02:20:21 INFO - 'LOGNAME': 'cltbld', 02:20:21 INFO - 'MAIL': '/var/mail/cltbld', 02:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:21 INFO - 'MOZ_NO_REMOTE': '1', 02:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:21 INFO - 'NO_EM_RESTART': '1', 02:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:21 INFO - 'PWD': '/builds/slave/test', 02:20:21 INFO - 'SHELL': '/bin/bash', 02:20:21 INFO - 'SHLVL': '1', 02:20:21 INFO - 'TERM': 'linux', 02:20:21 INFO - 'TMOUT': '86400', 02:20:21 INFO - 'USER': 'cltbld', 02:20:21 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:21 INFO - '_': '/tools/buildbot/bin/python'} 02:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:21 INFO - Downloading/unpacking flake8 02:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:20:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:20:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:20:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:20:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:20:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:20:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:20:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:20:24 INFO - no previously-included directories found matching 'docs/_build' 02:20:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:20:24 INFO - Running setup.py install for pep8 02:20:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:20:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:20:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:20:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:20:24 INFO - no previously-included directories found matching 'docs/_build' 02:20:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:20:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:20:24 INFO - Cleaning up... 02:20:24 INFO - Return code: 0 02:20:24 INFO - Running post-action listener: _resource_record_post_action 02:20:24 INFO - Running post-action listener: _start_resource_monitoring 02:20:24 INFO - Starting resource monitoring. 02:20:24 INFO - ##### 02:20:24 INFO - ##### Finished create-virtualenv step (success) 02:20:24 INFO - ##### 02:20:24 INFO - ##### 02:20:24 INFO - ##### Running install step. 02:20:24 INFO - ##### 02:20:24 INFO - Running pre-action listener: _resource_record_pre_action 02:20:24 INFO - Running main action method: install 02:20:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:20:24 INFO - Reading from file tmpfile_stdout 02:20:24 INFO - Detecting whether we're running mozinstall >=1.0... 02:20:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:20:24 INFO - Reading from file tmpfile_stdout 02:20:24 INFO - Output received: 02:20:24 INFO - Usage: mozinstall [options] installer 02:20:24 INFO - Options: 02:20:24 INFO - -h, --help show this help message and exit 02:20:24 INFO - -d DEST, --destination=DEST 02:20:24 INFO - Directory to install application into. [default: 02:20:24 INFO - "/builds/slave/test"] 02:20:24 INFO - --app=APP Application being installed. [default: firefox] 02:20:24 INFO - mkdir: /builds/slave/test/build/application 02:20:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:20:40 INFO - Reading from file tmpfile_stdout 02:20:40 INFO - Output received: 02:20:40 INFO - /builds/slave/test/build/application/firefox/firefox 02:20:40 INFO - Running post-action listener: _resource_record_post_action 02:20:40 INFO - ##### 02:20:40 INFO - ##### Finished install step (success) 02:20:40 INFO - ##### 02:20:40 INFO - ##### 02:20:40 INFO - ##### Running run-tests step. 02:20:40 INFO - ##### 02:20:40 INFO - Running pre-action listener: _resource_record_pre_action 02:20:40 INFO - Running main action method: run_tests 02:20:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:20:40 INFO - Python 2.7.3 02:20:40 INFO - Return code: 0 02:20:40 INFO - grabbing minidump binary from tooltool 02:20:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:40 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 0x22049c0>, '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 0x22058c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2205d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:20:40 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 02:20:40 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 02:20:40 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 02:20:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:20:40 INFO - Return code: 0 02:20:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:20:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:20:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:20:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:20:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:20:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:20:41 INFO - Return code: 0 02:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 02:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 02:20:41 INFO - Using env: {'DISPLAY': ':0', 02:20:41 INFO - 'HOME': '/home/cltbld', 02:20:41 INFO - 'LANG': 'en_US.UTF-8', 02:20:41 INFO - 'LANGUAGE': 'en_US:en', 02:20:41 INFO - 'LOGNAME': 'cltbld', 02:20:41 INFO - 'MAIL': '/var/mail/cltbld', 02:20:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:20:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:41 INFO - 'MOZ_NO_REMOTE': '1', 02:20:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:20:41 INFO - 'NO_EM_RESTART': '1', 02:20:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:41 INFO - 'PWD': '/builds/slave/test', 02:20:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:20:41 INFO - 'SHELL': '/bin/bash', 02:20:41 INFO - 'SHLVL': '1', 02:20:41 INFO - 'TERM': 'linux', 02:20:41 INFO - 'TMOUT': '86400', 02:20:41 INFO - 'USER': 'cltbld', 02:20:41 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884', 02:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:41 INFO - '_': '/tools/buildbot/bin/python'} 02:20:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 02:20:41 INFO - mozversion application_buildid: 20160517065136 02:20:41 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 02:20:41 INFO - mozversion application_display_name: Nightly 02:20:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:20:41 INFO - mozversion application_name: Firefox 02:20:41 INFO - mozversion application_remotingname: firefox 02:20:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:20:41 INFO - mozversion application_vendor: Mozilla 02:20:41 INFO - mozversion application_version: 49.0a1 02:20:41 INFO - mozversion platform_buildid: 20160517065136 02:20:41 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 02:20:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:20:41 INFO - mozversion platform_version: 49.0a1 02:20:41 INFO - using testdate: 1463649641 02:20:41 INFO - actual date: 1463649641 02:20:41 INFO - starting webserver on 'localhost:39899' 02:20:41 INFO - SUITE-START | Running 2 tests 02:20:41 INFO - TEST-START | dromaeo_css 02:20:41 INFO - operating with platform_type : linux_ 02:20:41 INFO - Initialising browser for dromaeo_css test... 02:20:41 INFO - TEST-INFO | started process 25704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSJUqbt/profile http://localhost:39899/getInfo.html) 02:20:45 INFO - PROCESS | 25704 | __metrics Screen width/height:1600/1200 02:20:45 INFO - PROCESS | 25704 | colorDepth:24 02:20:45 INFO - PROCESS | 25704 | Browser inner width/height: 1024/697 02:20:45 INFO - PROCESS | 25704 | __metrics 02:20:46 ERROR - PROCESS | 25704 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:20:47 INFO - TEST-INFO | 25704: exit 0 02:20:47 INFO - Browser initialized. 02:20:47 INFO - Running cycle 1/1 for dromaeo_css test... 02:20:47 INFO - TEST-INFO | started process 25787 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSJUqbt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:20:59 INFO - PROCESS | 25787 | RSS: Main: 153862144 02:20:59 INFO - PROCESS | 25787 | 02:21:50 INFO - PROCESS | 25787 | 1463649710175 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] 02:24:02 INFO - PROCESS | 25787 | Cycle 1(1): loaded http://localhost:39899/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39899/tests/dromaeo/cssquery-ext.html) 02:24:03 INFO - PROCESS | 25787 | RSS: Main: 242241536 02:24:03 INFO - PROCESS | 25787 | 02:27:06 INFO - PROCESS | 25787 | Cycle 1(1): loaded http://localhost:39899/tests/dromaeo/cssquery-ext.html (next: http://localhost:39899/tests/dromaeo/cssquery-jquery.html) 02:27:06 INFO - PROCESS | 25787 | RSS: Main: 237101056 02:27:06 INFO - PROCESS | 25787 | 02:30:09 INFO - PROCESS | 25787 | Cycle 1(1): loaded http://localhost:39899/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39899/tests/dromaeo/cssquery-mootools.html) 02:30:09 INFO - PROCESS | 25787 | RSS: Main: 232165376 02:30:09 INFO - PROCESS | 25787 | 02:33:11 INFO - PROCESS | 25787 | Cycle 1(1): loaded http://localhost:39899/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39899/tests/dromaeo/cssquery-prototype.html) 02:33:12 INFO - PROCESS | 25787 | RSS: Main: 232112128 02:33:12 INFO - PROCESS | 25787 | 02:36:15 INFO - PROCESS | 25787 | Cycle 1(1): loaded http://localhost:39899/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39899/tests/dromaeo/cssquery-yui.html) 02:36:15 INFO - PROCESS | 25787 | RSS: Main: 210788352 02:36:15 INFO - PROCESS | 25787 | 02:39:18 INFO - PROCESS | 25787 | Cycle 1(1): loaded http://localhost:39899/tests/dromaeo/cssquery-yui.html (next: http://localhost:39899/tests/dromaeo/cssquery-dojo.html) 02:39:19 INFO - PROCESS | 25787 | RSS: Main: 302923776 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | __start_tp_report 02:39:19 INFO - PROCESS | 25787 | _x_x_mozilla_page_load 02:39:19 INFO - PROCESS | 25787 | _x_x_mozilla_page_load_details 02:39:19 INFO - PROCESS | 25787 | |i|pagename|runs| 02:39:19 INFO - PROCESS | 25787 | |0;dojo.html;2620.030588682188;2645.325442012287;2650.4169082801777;2682.2221555748833;2685.6508653875007;4557.339185818063;4665.510121437243;4679.906401871961;4717.8112875484985;4791.592714619257;4695.436547614283;4695.882602934928;4708.576228139466;4758.143534163849;4762.904741905159;4286.678499112563;4300.35494675798;4371.519132895395;4396.450443694538;4499.842505512308;4390.2317094508335;4424.867253982386;4449.26587113126;4455.398521199645;4483.641308695313;4264.445622069111;4266.338717498762;4271.850485233002;4306.5262821089655;4308.5045219799495;4259.74441533509;4303.311470164758;4318.201132790444;4320.546342634041;4371.978140109279;4291.0559676871035;4309.267424537836;4313.547077556843;4337.067530480962;4348.2607956647125;4536;4643.187442197602;4690.343351930733;4700.459447163552;4711.670183087185;5157.5616072633875;5167.844964651066;5171.5862730981435;5183.067047931371;5187.351581052369;4966.776495057695;5182.093133701587;5182.170852663556;5220.112580861262;5226.608004399685;4677.158111539921;4716.056788642285;4719.882002949885;4745.406824146981;4773.761311934459;5190.403103643054;5236.0575096483;5240.580753539708;5242.082635538765;5249.133892907627;4779.832705855279;4974.054929563371;4982.402111746614;5016.372953380827;5023.095842748267;5111.642185047011;5112.412072611697;5126.769295381717;5128.769205385766;5172.560332371715;5092.261622064854;5102.795888164506;5105.5149760772665;5108.182690769533;5152.845414637567;4821.987382649683;4856.68431551948;4951.356323677899;5063.215201643751;5075;4282.35764635301;4290.163418133434;4300.354946757949;4334.718243314174;4345.34819777036;3740.3454395481226;3751.362268414376;3760.1539653577724;3792.4311353296675;3801.885943421702;4266.189424009428;4288.592583704543;4301.397804307399;4316.374125751749;4357.3899654048455;4975.353204083446;5028.748562571856;5033.194688849767;5046.470120637354;5077.111505486525;4302.096559722493;4305.182015417061;4305.418768466279;4306.569343065668;4350.521442641307;4406.449193850769;4573.199690054227;4604.263317869125;4615.076984603093;4615.630749540029;4826.0589185108565;4840.2255639097575;4854.053459575349;4855.101806165871;4883.877903052452;4962.379702537183;5147.897042059213;5157.922631160461;5159.303494028258;5212.9739351304515;4753.168195565693;4783.689060211075;4796.112719146969;4807.95192048075;4815.831445899376;5009.9248511271635;5032.899342013066;5034.244863270539;5034.44621091687;5075.3148324975655;4903.41159060915;5056.115179843439;5061.848144555669;5072.594192464668;5130.281760553451;4927.852164435073;4970.279309500174;4994.875128121681;4997.075541024922;5041.596672266283;4969.602431805374;4989.650724449254;4989.650724449254;4992.675476094042;5039.84880453587;4793.257045158006;4843.1524483216;4864.537868902448;4908.190148625579;4970.527799859007;4948.727819969936;4964.478729733471;4975.502449754996;4976.2286845539;5006.374203224597;4864.6594738369395;4875.414950205998;4878.146324393288;4885.780139893643;4920.458749537476 02:39:19 INFO - PROCESS | 25787 | |1;ext.html;573.7790950484062;578.5082679722236;582.6125626458405;602.400611391665;610.4810910725884;40886.38670419942;42149.57850421495;42213.15573688532;42233.36649950253;46344.304835427494;43502.34746478798;43891.56108438923;43974.560254397446;43982.56017439825;44026.55973440265;7880.527168369902;8016.759497215093;8020.438569300151;8020.478668886532;8021.558814265213;7886.132525422199;7955;7981.082175549799;7981.082175549828;7982.880256796153;8003.559804210766;8040.115587285394;8041.0752763432365;8043.235892590194;8045.436819422643;7937.761867143966;7986.880196797082;7999.040115186157;8003.1996800320085;8003.679852805881;7954.522728636301;8017.839643207161;8022.919770802334;8023.237792409712;8025.197480251928;40391.19217615631;41056.794716026525;41154;41159.7942010288;41227.175456491;3269.705726484604;3357.597088349389;3400.7279417646528;3864.265789499986;3936.783476908769;29759;30145.0956471304;30304.69695303041;30737.231569210948;31359.686403135904;3224.69365410288;3231.434498962672;3243.7405007599573;3267.2648654052887;3315.635280119185;263701;264479;265080;265298;265473;29674.1097767063;30674;30687;59020;59277.703611481666;34433.31133377369;34465.31069378649;34590.65409345925;34602;34607.30785384278;13807.378667959765;13889.79165312521;13928.721425571635;13948.372323245478;13961.581152565439;11842.76314473723;11899.048076153887;12006.579769708018;12026.819597706042;12131.454084566216;8470.322374210049;8476.872846907301;8483.236508714246;8534.317254619615;8573.22840944318;3267.983660081732;3268.444364458048;3278.164068162603;3293.5883014623173;3336.866525338959;904.5432056811268;923.6905636611676;949.4018768176005;988.1748739802346;1041.1722680469045;28208.29479262994;29075.56386654202;29255.56116658252;29593.26016849553;29779;999.5502024089044;1023.8668973033607;1068.4444089073793;1085.7285678580356;1107.2249425402251;15210.391584336503;15591.142487163315;15645.921770391074;15657.921710391374;15879.206039697969;369046.4643030303;373216;374482;374846;375785;52531;53853;53886.46113538893;53932.73033634807;54072.45927540674;1145.381493993251;1231.6428235811693;1272.2620879889641;1285.1325355385077;1291.180100636103;29084.418311633224;30364.089077327717;30885.227869303446;31079.68920310768;32841.83579082089;50524.24213636872;50916.23625645544;51415.74292128516;51420.48579514307;51446;345755;346126;348560;349154;350385;33570.66429335676;39210.411843822934;40176.59823401846;40337;43062.78468607637;44178.33732493951;44295.557044429144;44707.552924470336;45070.774646126556;45396.54603454055;36242.4563631551;37500.2499949994;37669.24661506699;37954.2409151821;38137.6186238134;37324.6267537332;37487.25025499529;37755.43366849554;38025;38365.80817096008;36927.44608830923;37609;38543.614563854004;38577.228455431294;38682.22635547329;31652.208694782814;33453.66546334505;33762.3247535043;34071.31857362889;34311.82844085764;26116.34709132211;26155.215343539723;26203.606945895444;26204.475910481302;26480.602790957764 02:39:19 INFO - PROCESS | 25787 | |2;jquery.html;379.59383459698125;386.33935969492165;388.6696308138083;398.4382021349895;403.95354534228574;16419.50741477756;17845.464636060908;18807.24771009158;18809.905950470245;18827.246910123595;17582.560435989108;17939.64120717585;18058.187381567826;18412.44762657119;18419.63160736788;5105.514976077276;5225.111731005738;5231.712255825928;5250.789968401259;5283.260343551906;5171.482851714817;5492.917606235909;5504.284443022401;5551.278333816618;5578.191162281467;5539.196816461611;5547;5548.389677135512;5557.138643510242;5643.887122257553;5888.322842873082;5997.880042399128;6012.819615411545;6023.969880150593;6026.578139530235;4674.719516828967;4979.45226025137;5324.467553244684;5325.627206095575;5328.200769884548;12588.05589580785;12852.35738213095;12968;13054.151480153854;13128.60614181576;1912.129980858706;1914.7893731689503;1994.2621230144794;2000.9099590518315;2016.5261163626535;9116.453012819185;9424.387414818015;9510.191633711143;9526.90440599333;9582.90417095827;1708.103245795967;1709.7264437690035;1754.0791084680518;1779.243821375923;1795.5062357851564;69619.65190174017;71237;71318;71438;71911.28088719098;10297.227707921937;10365.792684146425;10516.316439431412;10606.363618182933;10821.350718956888;14473.348699308557;14712.55862324132;14716.926415368069;14738.705225895423;14745.041572297767;7467.7012919483695;7712.5758083305955;7740.574268415291;7827.295543401121;7832.099308579473;7368.336849683555;7375.44684148691;7487.775366739006;7520.1351844537485;7520.1351844537485;4401.911961760747;4456.64346852251;4556.2937744649635;4594.908101838011;4607.995521947963;1815.9818401816076;1856.3873921605837;1901.4865986183654;1937.4575118966711;1956.403296994402;914.5838643417362;935.8736570562887;944.1455482788087;958.5351104714208;999.9700308682069;17244.137793110294;17488.65022699539;17498.737518937225;17723.379681711078;17796.46610601684;903.1826197291462;906.71891713569;930.5812384427035;935.8081593273363;955.9904400956041;9861.506924653739;10003.24975626831;10069.647562335284;10279.588816447258;10340.741481462874;362363;368716;375492;377961;377985;65331.67334163299;75971.62014190004;80387;84118;86690;920.8342498350361;941.2303723587127;948.4546385828177;960.3757557587485;962.8748262725801;16554.82324745366;16645.750313745062;16689.49931502057;17018.829811701726;17194.140292985052;36917.26165476729;37450.8127459361;37839.43240851334;38892.6110738889;38966.6103338963;53728.194077089654;53967;54849;55475.33573992787;55538;5792.53659707214;7763.262490063435;7770.572618506037;7974.082980457321;8057.717979870677;8765.78085547841;11632.941835290769;11808.992471279731;11860.584879529175;12011.939940300243;5555.0278701227935;7988.880166797619;9113.544322783968;10509;10592.205584581034;10110.848337275094;10133.594656213669;10202.795944080928;10219.233557483367;10550.103241224582;10518.684439466828;10519.94740026295;10745.623903163352;10774.919486302644;11017.559297628006;6844.349786770249;7184.6048467334795;7445.181030086795;7988.201179881965;8066.596670166586;8689.565521724015;10802.297850639683;10937.398443085707;10945.124390048937;11047.558097676325 02:39:19 INFO - PROCESS | 25787 | |3;mootools.html;1303.8956883449325;1522.7411340072188;1538.6999535090658;1703.812580616132;1873.8313551780336;7084.114485689289;7543.547387156767;7692.961535192316;7698.230176982306;7895.249951254622;7615.77152685419;7770.456068075223;7781.844363112735;7787.64955576999;7787.9221207787905;2161.4704397422615;2183.836212284077;2187.6937228787983;2188.0810059774853;2190.37574291327;2177.553601511694;2198.472366632013;2218.101668824129;2223.5775202711434;2224.132588290568;2167.1331467413;2175.41263858758;2185.868847869125;2189.8029177374033;2217.168561789329;2151.075037733774;2153.83846211534;2159.7408311002623;2162.1891790578534;2164.6211912915255;2126.064531606088;2162.664786958024;2163.588918105555;2164.2425151197112;2164.6969424280614;7633.5419874807685;7764.301212890867;7771;7914;8174.509529428194;4635.884102897421;4733.95266047342;4742.596879265267;4743.549362810528;4745.786439610191;3016.8491575421363;3053.1603808952495;3088.7683423743083;3100.5659207710937;3100.612423447069;880.4013270975734;884.3765145572356;892.312919052326;893.066745251214;907.129156010231;10880.238383313172;10884.782304353868;10909.509072091774;10916.836247456295;10950.288231265022;3994.8601798936897;4076.5719599442223;4152.896177595524;4153.709240353207;4174.269502837052;8607.78480537979;8650.178233067849;8653.740387788377;8662.740117796477;8666.436681615674;4718.221493453541;4718.740469274223;4721.220998535272;4721.976390118096;4725.3384526166365;3832.3293423651307;4129.607687269705;4229.894252643647;4273.337632666941;4299.634531064832;3352.6814952579953;3384.4584866421255;3413.897583072462;3420.7935881356384;3456.688897999142;972.2853702528636;981.6367943860818;990.1633120013577;992.2954702161402;995.8506224066448;752.8870669399524;773.2731232641299;818.5048045932244;896.7354630384052;899.8875140607424;5226.346706661668;5340.49265319794;5370.91943620846;5394.73026348681;5431.429699881455;844.7845799321134;880.6257340630335;907.6505545365083;929.4609126706493;934.2245935873209;3581.94627080594;3713.9071523212137;3768.4347347898033;3787.8484860605818;3862.227554489057;9893.802123957625;10080.495975201211;10193.541290641939;10211.489425528694;10218.131458826083;9988.300818942604;10080.193584513221;10099.64651237218;10106.292559520763;10122.54448549817;7215.783526494213;7638.777795552684;7681.001469808889;7729.884051739341;7732.149463559004;5284.841454756363;5396.595255355785;5482.725863706719;5515.61390702647;5543.501084902378;8917.910820891968;9276.02601726809;9302.16280534755;9321;9431.669891553762;9391.906080939376;9473.194778443909;9498.382605130644;9503.619855205714;9511.096445837635;6212.409821066992;6808.251092379934;6899.344562266577;7158.642067896689;7307.963460182665;7344.48588598793;7363.484556081023;7421.0723659542555;7427.368673662799;7429.702811887464;6482.416582507597;6658.167729033871;6671.33286671329;6754.223264324665;6777.6272305023695;6536.248331441945;6564.540482166202;6583.3087525811015;6631.237407697983;6667.833304167435;6024.608400453956;6029.065494848305;6057.303410107894;6060.484858786876;6064.02975523914;4268.231718290737;4313.633341166036;4363.083752412029;4365.956340436555;4388.802503887397;4499.1451624191295;4624.768761561976;4647.326137709945;4658.464276608098;4660.20776468008 02:39:19 INFO - PROCESS | 25787 | |4;prototype.html;294.5978739021236;300.3242703916189;300.9548567714841;304.1923692596158;307.9692030796921;4839.4918533554;4844.61243100552;4855.562999330064;4863.392075990501;4888.364512613364;4797.376341075664;4806.279058141281;4807.2789081637775;4817.638677099214;4834.734089625069;3634.4003239465455;3707.703383729309;3737.9813100934457;3786.602406747293;3806.486124373202;3768.5100936878166;3789.2421515696833;3789.578907909534;3800.8977396555106;3803.5055442792536;3561.5904171020406;3753.7800214930257;3766.1526156614677;3773.6603705666485;3781.6596506314427;3632.146445585285;3775.9433608495897;3796.8101594920417;3800.1449673823445;3810.7523011004196;3329.51722000311;3338.232206592473;3341.148007258158;3346.1300061983816;3353.5305057292;4543.341215523764;4589.495155532889;4616.76916154191;4621.976890115528;4624.676272660915;4212.620864122244;4316.460442444694;4321.0709697415205;4323.762193079379;4329.826806927719;4958.752062396866;4964.925526117111;4974.527419895104;4982.800687972477;4985.975070124662;3614.102596137023;3627.2020155565733;3642.979965609607;3643.0892276930767;3646.197836475971;4966.453690094087;5114.616403769695;5200.973995130038;5200.973995130038;5215.16557350822;3697.205100903265;3712.4431335299923;3716.955674379081;3726.1802403471193;3729.384651532521;3763.9435408468894;3770.2271034437877;3770.792606406619;3775.7356985011334;3780.962190378061;4244.936325955113;4254.127903779718;4263.253930562139;4269.210196113729;4278.486581610165;4782.402199725035;4984.626153038536;4986.675866068694;4993.550580447777;4997.3253610762085;1289.2006955687345;1297.5004623220127;1301.947922083106;1306.5296493262417;1309.8886594639562;842.768238734353;852.6802449081595;885.2121611765534;900;904.9230815380766;4341.82632694695;4352.630026447724;4358.847440339573;4388.341748737651;4395.912081758347;5000.324956130876;5062.190049592047;5075.63982748024;5081.8475445736685;5119.745662312757;4245.554216807191;4305.363961694598;4332.956670433318;4364.4980827204645;4397.768624785065;2692.488427198826;2710.7259587994004;2712.986435067812;2715.4433341165013;2747.738964798341;4736.360591320127;4737.28940658904;4757.26262429324;4782.52174782519;4786.928196077061;5053.418856831438;5180.611454140954;5182.300389447451;5198.610104242197;5350.91973620396;4890.144224760653;4900.730459824673;4914.115459217375;4921.548143297736;4925.5074492551175;5189.4032186299055;5207.411739419514;5215.217717342429;5217.895642087061;5221.712805795718;4858.392700912386;5123.846284611468;5124.897502050013;5125.231215317732;5129.948700513097;4897.4857639947295;4915.483874193158;4934.432540257916;4936.605071594192;4937.629677774253;3598.758428342218;3613.837377317974;3625.9456108158925;3632.709383249281;3639.217568222845;5034.496550344937;5170.699038927641;5204.906969536289;5210.609204309768;5217.139172036572;5008.87477813043;5029.396472423333;5033.597312214941;5041.747912604282;5045.9243111352625;5006.349174607278;5026.59787217029;5037.874053148701;5078.4159821619505;5095.4395016547405;4756.191447453872;4930.728809915346;4947.950520494749;5014.824481143142;5026.321446604661;4864.713584615225;4896.730679812645;5022.422421421583;5044.949550504448;5070.340855688737;4897.730624815669;4904.288878112582;4952.380952380952;4952.702837829742;4974.801007959786 02:39:19 INFO - PROCESS | 25787 | |5;yui.html;679.9490038247131;716.2372073741468;802.4022103532869;805.5448671500602;813.2263612594073;12478.126531142812;12913.806292905614;12961.805572916413;12967.805482917753;12987.090903636748;13055.151415158009;13068.673283167926;13105.80341294879;13109.082364234508;13110.27893465859;661.5170925224589;831.9001719793603;849.1847826086948;862.65062649627;873.7422460374205;858.3690987124489;859.2009431228954;862.4738909265343;862.65062649627;863.0592654007131;920.0477505781517;920.0753242990785;920.3189639666665;920.7652048727565;920.8664743612172;698.5284932670453;882.1487264789445;908.8000639859221;909.8771665825095;910.0217266438572;676.6447615002163;676.7090151234967;676.7462201674372;677.3395938959525;677.5832862789398;12485.375731213493;12596.370181490889;12596.496140154382;12600.495980160782;12624.873751262554;11721;12070.517179312817;12156.45295961675;12452.875471245263;12684.936575317064;10890.727731806688;10968;10992;10994.230403871732;11410.20128590999;4177.728447650893;4280.850170244058;4323;4381.561843815625;4389.582989615981;44721.32918006233;45195.77402113001;45218.095638087056;45294;45433.77283113563;10300.278980471296;10464;10566.366018038942;10583.894161058292;10991.120710343153;16723.331066757193;18891.055447227583;18906.243750249836;18927.432177034712;18937.90531047336;7058.435325173974;7236.782896513113;7311.817204569822;7331.04696389466;7435.442341824277;3938.8030598469963;3992.8203230854683;4033.3278681194574;4048.4534587830854;4129.215449064668;2822.2662107852107;2904.607877936494;2905.6077429547163;2910.0833237530533;2910.5052141136057;516.302990962198;532.5792623827133;532.8987492376436;543.8015124479537;581.6016029020135;575.9164921086419;588.0825911577953;599.598269159664;604.5224272824507;608.7321578505444;7749.612519374009;7833.216678332121;7864.921350786533;7882.763517094496;8179;547.2584647802171;591.1546488521369;604.4378727783204;613.8520965793994;626.9874602507924;3666.6185352446764;3692.630736926286;3753.4932784074344;3781.6785573226034;3798.2973149967343;58541.4145858536;60566.394336056954;60736;60736.69631651902;60753.924607539775;43273;44556.77721611371;48306;49858.50141498611;51341.22988155181;42191;47753.28370074452;48053.51946480491;48566.27150592744;49055.75472122688;8352.540610266493;8502.702405416028;8628.956855215683;8644.660271306442;8774.048056198835;31002.379952400373;31259.84370078135;31453.68546314599;31483.685163148075;32318.192045198117;38451.230975379774;39523.60476395199;42472;42632;42968.14063718771;11116.833247501456;11153.107751379708;11209.327440353605;11215.831762523732;11262;11737.471813768572;12004.39978001079;12364;12381.257124572554;12770.744585108432;11349.432528373713;11696.649100526998;11956.043516518832;12288.262704237774;12307.26156430617;11531.942340288246;11727.179097463437;11756.647300580997;12114.697132571404;12181.573644922739;11253.235267665284;11270.887291126985;11338.263012904135;11509.884901150881;11760.88239117598;9436.103570160823;9512.626540217932;9771.511424428609;9830.213582913493;10191.694249172537;9682.661106861508;9690.12788849007;9816.607335706492;9829;9829.950850245703 02:39:19 INFO - PROCESS | 25787 | __end_tp_report 02:39:19 INFO - PROCESS | 25787 | __start_cc_report 02:39:19 INFO - PROCESS | 25787 | _x_x_mozilla_cycle_collect,887 02:39:19 INFO - PROCESS | 25787 | __end_cc_report 02:39:19 INFO - PROCESS | 25787 | __startTimestamp1463650759101__endTimestamp 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | ------- Summary: start ------- 02:39:19 INFO - PROCESS | 25787 | Number of tests: 6 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | [#0] dojo.html Cycles:165 Average:4689.52 Median:4821.99 stddev:503.23 (10.4%) stddev-sans-first:477.87 02:39:19 INFO - PROCESS | 25787 | Values: 2620.0 2645.3 2650.4 2682.2 2685.7 4557.3 4665.5 4679.9 4717.8 4791.6 4695.4 4695.9 4708.6 4758.1 4762.9 4286.7 4300.4 4371.5 4396.5 4499.8 4390.2 4424.9 4449.3 4455.4 4483.6 4264.4 4266.3 4271.9 4306.5 4308.5 4259.7 4303.3 4318.2 4320.5 4372.0 4291.1 4309.3 4313.5 4337.1 4348.3 4536.0 4643.2 4690.3 4700.5 4711.7 5157.6 5167.8 5171.6 5183.1 5187.4 4966.8 5182.1 5182.2 5220.1 5226.6 4677.2 4716.1 4719.9 4745.4 4773.8 5190.4 5236.1 5240.6 5242.1 5249.1 4779.8 4974.1 4982.4 5016.4 5023.1 5111.6 5112.4 5126.8 5128.8 5172.6 5092.3 5102.8 5105.5 5108.2 5152.8 4822.0 4856.7 4951.4 5063.2 5075.0 4282.4 4290.2 4300.4 4334.7 4345.3 3740.3 3751.4 3760.2 3792.4 3801.9 4266.2 4288.6 4301.4 4316.4 4357.4 4975.4 5028.7 5033.2 5046.5 5077.1 4302.1 4305.2 4305.4 4306.6 4350.5 4406.4 4573.2 4604.3 4615.1 4615.6 4826.1 4840.2 4854.1 4855.1 4883.9 4962.4 5147.9 5157.9 5159.3 5213.0 4753.2 4783.7 4796.1 4808.0 4815.8 5009.9 5032.9 5034.2 5034.4 5075.3 4903.4 5056.1 5061.8 5072.6 5130.3 4927.9 4970.3 4994.9 4997.1 5041.6 4969.6 4989.7 4989.7 4992.7 5039.8 4793.3 4843.2 4864.5 4908.2 4970.5 4948.7 4964.5 4975.5 4976.2 5006.4 4864.7 4875.4 4878.1 4885.8 4920.5 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | [#1] ext.html Cycles:180 Average:48516.57 Median:206642.62 stddev:87396.47 (42.3%) stddev-sans-first:87567.51 02:39:19 INFO - PROCESS | 25787 | Values: 573.8 578.5 582.6 602.4 610.5 40886.4 42149.6 42213.2 42233.4 46344.3 43502.3 43891.6 43974.6 43982.6 44026.6 7880.5 8016.8 8020.4 8020.5 8021.6 7886.1 7955.0 7981.1 7981.1 7982.9 8003.6 8040.1 8041.1 8043.2 8045.4 7937.8 7986.9 7999.0 8003.2 8003.7 7954.5 8017.8 8022.9 8023.2 8025.2 40391.2 41056.8 41154.0 41159.8 41227.2 3269.7 3357.6 3400.7 3864.3 3936.8 29759.0 30145.1 30304.7 30737.2 31359.7 3224.7 3231.4 3243.7 3267.3 3315.6 263701.0 264479.0 265080.0 265298.0 265473.0 29674.1 30674.0 30687.0 59020.0 59277.7 34433.3 34465.3 34590.7 34602.0 34607.3 13807.4 13889.8 13928.7 13948.4 13961.6 11842.8 11899.0 12006.6 12026.8 12131.5 8470.3 8476.9 8483.2 8534.3 8573.2 3268.0 3268.4 3278.2 3293.6 3336.9 904.5 923.7 949.4 988.2 1041.2 28208.3 29075.6 29255.6 29593.3 29779.0 999.6 1023.9 1068.4 1085.7 1107.2 15210.4 15591.1 15645.9 15657.9 15879.2 369046.5 373216.0 374482.0 374846.0 375785.0 52531.0 53853.0 53886.5 53932.7 54072.5 1145.4 1231.6 1272.3 1285.1 1291.2 29084.4 30364.1 30885.2 31079.7 32841.8 50524.2 50916.2 51415.7 51420.5 51446.0 345755.0 346126.0 348560.0 349154.0 350385.0 33570.7 39210.4 40176.6 40337.0 43062.8 44178.3 44295.6 44707.6 45070.8 45396.5 36242.5 37500.2 37669.2 37954.2 38137.6 37324.6 37487.3 37755.4 38025.0 38365.8 36927.4 37609.0 38543.6 38577.2 38682.2 31652.2 33453.7 33762.3 34071.3 34311.8 26116.3 26155.2 26203.6 26204.5 26480.6 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | [#2] jquery.html Cycles:180 Average:24037.74 Median:4506.47 stddev:61769.10 (1370.7%) stddev-sans-first:61916.84 02:39:19 INFO - PROCESS | 25787 | Values: 379.6 386.3 388.7 398.4 404.0 16419.5 17845.5 18807.2 18809.9 18827.2 17582.6 17939.6 18058.2 18412.4 18419.6 5105.5 5225.1 5231.7 5250.8 5283.3 5171.5 5492.9 5504.3 5551.3 5578.2 5539.2 5547.0 5548.4 5557.1 5643.9 5888.3 5997.9 6012.8 6024.0 6026.6 4674.7 4979.5 5324.5 5325.6 5328.2 12588.1 12852.4 12968.0 13054.2 13128.6 1912.1 1914.8 1994.3 2000.9 2016.5 9116.5 9424.4 9510.2 9526.9 9582.9 1708.1 1709.7 1754.1 1779.2 1795.5 69619.7 71237.0 71318.0 71438.0 71911.3 10297.2 10365.8 10516.3 10606.4 10821.4 14473.3 14712.6 14716.9 14738.7 14745.0 7467.7 7712.6 7740.6 7827.3 7832.1 7368.3 7375.4 7487.8 7520.1 7520.1 4401.9 4456.6 4556.3 4594.9 4608.0 1816.0 1856.4 1901.5 1937.5 1956.4 914.6 935.9 944.1 958.5 1000.0 17244.1 17488.7 17498.7 17723.4 17796.5 903.2 906.7 930.6 935.8 956.0 9861.5 10003.2 10069.6 10279.6 10340.7 362363.0 368716.0 375492.0 377961.0 377985.0 65331.7 75971.6 80387.0 84118.0 86690.0 920.8 941.2 948.5 960.4 962.9 16554.8 16645.8 16689.5 17018.8 17194.1 36917.3 37450.8 37839.4 38892.6 38966.6 53728.2 53967.0 54849.0 55475.3 55538.0 5792.5 7763.3 7770.6 7974.1 8057.7 8765.8 11632.9 11809.0 11860.6 12011.9 5555.0 7988.9 9113.5 10509.0 10592.2 10110.8 10133.6 10202.8 10219.2 10550.1 10518.7 10519.9 10745.6 10774.9 11017.6 6844.3 7184.6 7445.2 7988.2 8066.6 8689.6 10802.3 10937.4 10945.1 11047.6 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | [#3] mootools.html Cycles:180 Average:5183.82 Median:5355.71 stddev:2949.51 (55.1%) stddev-sans-first:2943.37 02:39:19 INFO - PROCESS | 25787 | Values: 1303.9 1522.7 1538.7 1703.8 1873.8 7084.1 7543.5 7693.0 7698.2 7895.2 7615.8 7770.5 7781.8 7787.6 7787.9 2161.5 2183.8 2187.7 2188.1 2190.4 2177.6 2198.5 2218.1 2223.6 2224.1 2167.1 2175.4 2185.9 2189.8 2217.2 2151.1 2153.8 2159.7 2162.2 2164.6 2126.1 2162.7 2163.6 2164.2 2164.7 7633.5 7764.3 7771.0 7914.0 8174.5 4635.9 4734.0 4742.6 4743.5 4745.8 3016.8 3053.2 3088.8 3100.6 3100.6 880.4 884.4 892.3 893.1 907.1 10880.2 10884.8 10909.5 10916.8 10950.3 3994.9 4076.6 4152.9 4153.7 4174.3 8607.8 8650.2 8653.7 8662.7 8666.4 4718.2 4718.7 4721.2 4722.0 4725.3 3832.3 4129.6 4229.9 4273.3 4299.6 3352.7 3384.5 3413.9 3420.8 3456.7 972.3 981.6 990.2 992.3 995.9 752.9 773.3 818.5 896.7 899.9 5226.3 5340.5 5370.9 5394.7 5431.4 844.8 880.6 907.7 929.5 934.2 3581.9 3713.9 3768.4 3787.8 3862.2 9893.8 10080.5 10193.5 10211.5 10218.1 9988.3 10080.2 10099.6 10106.3 10122.5 7215.8 7638.8 7681.0 7729.9 7732.1 5284.8 5396.6 5482.7 5515.6 5543.5 8917.9 9276.0 9302.2 9321.0 9431.7 9391.9 9473.2 9498.4 9503.6 9511.1 6212.4 6808.3 6899.3 7158.6 7308.0 7344.5 7363.5 7421.1 7427.4 7429.7 6482.4 6658.2 6671.3 6754.2 6777.6 6536.2 6564.5 6583.3 6631.2 6667.8 6024.6 6029.1 6057.3 6060.5 6064.0 4268.2 4313.6 4363.1 4366.0 4388.8 4499.1 4624.8 4647.3 4658.5 4660.2 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | [#4] prototype.html Cycles:180 Average:4170.24 Median:4784.72 stddev:1199.14 (25.1%) stddev-sans-first:1166.69 02:39:19 INFO - PROCESS | 25787 | Values: 294.6 300.3 301.0 304.2 308.0 4839.5 4844.6 4855.6 4863.4 4888.4 4797.4 4806.3 4807.3 4817.6 4834.7 3634.4 3707.7 3738.0 3786.6 3806.5 3768.5 3789.2 3789.6 3800.9 3803.5 3561.6 3753.8 3766.2 3773.7 3781.7 3632.1 3775.9 3796.8 3800.1 3810.8 3329.5 3338.2 3341.1 3346.1 3353.5 4543.3 4589.5 4616.8 4622.0 4624.7 4212.6 4316.5 4321.1 4323.8 4329.8 4958.8 4964.9 4974.5 4982.8 4986.0 3614.1 3627.2 3643.0 3643.1 3646.2 4966.5 5114.6 5201.0 5201.0 5215.2 3697.2 3712.4 3717.0 3726.2 3729.4 3763.9 3770.2 3770.8 3775.7 3781.0 4244.9 4254.1 4263.3 4269.2 4278.5 4782.4 4984.6 4986.7 4993.6 4997.3 1289.2 1297.5 1301.9 1306.5 1309.9 842.8 852.7 885.2 900.0 904.9 4341.8 4352.6 4358.8 4388.3 4395.9 5000.3 5062.2 5075.6 5081.8 5119.7 4245.6 4305.4 4333.0 4364.5 4397.8 2692.5 2710.7 2713.0 2715.4 2747.7 4736.4 4737.3 4757.3 4782.5 4786.9 5053.4 5180.6 5182.3 5198.6 5350.9 4890.1 4900.7 4914.1 4921.5 4925.5 5189.4 5207.4 5215.2 5217.9 5221.7 4858.4 5123.8 5124.9 5125.2 5129.9 4897.5 4915.5 4934.4 4936.6 4937.6 3598.8 3613.8 3625.9 3632.7 3639.2 5034.5 5170.7 5204.9 5210.6 5217.1 5008.9 5029.4 5033.6 5041.7 5045.9 5006.3 5026.6 5037.9 5078.4 5095.4 4756.2 4930.7 4948.0 5014.8 5026.3 4864.7 4896.7 5022.4 5044.9 5070.3 4897.7 4904.3 4952.4 4952.7 4974.8 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - PROCESS | 25787 | [#5] yui.html Cycles:180 Average:13531.41 Median:23827.28 stddev:15514.88 (65.1%) stddev-sans-first:15528.39 02:39:19 INFO - PROCESS | 25787 | Values: 679.9 716.2 802.4 805.5 813.2 12478.1 12913.8 12961.8 12967.8 12987.1 13055.2 13068.7 13105.8 13109.1 13110.3 661.5 831.9 849.2 862.7 873.7 858.4 859.2 862.5 862.7 863.1 920.0 920.1 920.3 920.8 920.9 698.5 882.1 908.8 909.9 910.0 676.6 676.7 676.7 677.3 677.6 12485.4 12596.4 12596.5 12600.5 12624.9 11721.0 12070.5 12156.5 12452.9 12684.9 10890.7 10968.0 10992.0 10994.2 11410.2 4177.7 4280.9 4323.0 4381.6 4389.6 44721.3 45195.8 45218.1 45294.0 45433.8 10300.3 10464.0 10566.4 10583.9 10991.1 16723.3 18891.1 18906.2 18927.4 18937.9 7058.4 7236.8 7311.8 7331.0 7435.4 3938.8 3992.8 4033.3 4048.5 4129.2 2822.3 2904.6 2905.6 2910.1 2910.5 516.3 532.6 532.9 543.8 581.6 575.9 588.1 599.6 604.5 608.7 7749.6 7833.2 7864.9 7882.8 8179.0 547.3 591.2 604.4 613.9 627.0 3666.6 3692.6 3753.5 3781.7 3798.3 58541.4 60566.4 60736.0 60736.7 60753.9 43273.0 44556.8 48306.0 49858.5 51341.2 42191.0 47753.3 48053.5 48566.3 49055.8 8352.5 8502.7 8629.0 8644.7 8774.0 31002.4 31259.8 31453.7 31483.7 32318.2 38451.2 39523.6 42472.0 42632.0 42968.1 11116.8 11153.1 11209.3 11215.8 11262.0 11737.5 12004.4 12364.0 12381.3 12770.7 11349.4 11696.6 11956.0 12288.3 12307.3 11531.9 11727.2 11756.6 12114.7 12181.6 11253.2 11270.9 11338.3 11509.9 11760.9 9436.1 9512.6 9771.5 9830.2 10191.7 9682.7 9690.1 9816.6 9829.0 9830.0 02:39:19 INFO - PROCESS | 25787 | -------- Summary: end -------- 02:39:19 INFO - PROCESS | 25787 | 02:39:19 INFO - TEST-INFO | 25787: exit 0 02:39:19 INFO - TEST-OK | dromaeo_css | took 1118078ms 02:39:19 INFO - TEST-START | kraken 02:39:19 INFO - operating with platform_type : linux_ 02:39:19 INFO - Initialising browser for kraken test... 02:39:19 INFO - TEST-INFO | started process 25968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZoaP49/profile http://localhost:39899/getInfo.html) 02:39:23 INFO - PROCESS | 25968 | __metrics Screen width/height:1600/1200 02:39:23 INFO - PROCESS | 25968 | colorDepth:24 02:39:23 INFO - PROCESS | 25968 | Browser inner width/height: 1024/697 02:39:23 INFO - PROCESS | 25968 | __metrics 02:39:24 ERROR - PROCESS | 25968 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:39:25 INFO - TEST-INFO | 25968: exit 0 02:39:25 INFO - Browser initialized. 02:39:25 INFO - Running cycle 1/1 for kraken test... 02:39:25 INFO - TEST-INFO | started process 26052 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZoaP49/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:39:37 INFO - PROCESS | 26052 | RSS: Main: 153931776 02:39:37 INFO - PROCESS | 26052 | 02:39:40 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39899/tests/kraken/driver.html?testName=audio-beat-detection) 02:39:41 INFO - PROCESS | 26052 | RSS: Main: 210137088 02:39:41 INFO - PROCESS | 26052 | 02:39:44 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39899/tests/kraken/driver.html?testName=audio-dft) 02:39:44 INFO - PROCESS | 26052 | RSS: Main: 197976064 02:39:44 INFO - PROCESS | 26052 | 02:39:48 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39899/tests/kraken/driver.html?testName=audio-fft) 02:39:48 INFO - PROCESS | 26052 | RSS: Main: 266616832 02:39:48 INFO - PROCESS | 26052 | 02:39:50 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39899/tests/kraken/driver.html?testName=audio-oscillator) 02:39:51 INFO - PROCESS | 26052 | RSS: Main: 175943680 02:39:51 INFO - PROCESS | 26052 | 02:39:53 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39899/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:39:54 INFO - PROCESS | 26052 | RSS: Main: 179576832 02:39:54 INFO - PROCESS | 26052 | 02:39:57 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39899/tests/kraken/driver.html?testName=imaging-darkroom) 02:39:58 INFO - PROCESS | 26052 | RSS: Main: 216076288 02:39:58 INFO - PROCESS | 26052 | 02:40:02 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39899/tests/kraken/driver.html?testName=imaging-desaturate) 02:40:02 INFO - PROCESS | 26052 | RSS: Main: 214618112 02:40:02 INFO - PROCESS | 26052 | 02:40:05 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39899/tests/kraken/driver.html?testName=json-parse-financial) 02:40:06 INFO - PROCESS | 26052 | RSS: Main: 214716416 02:40:06 INFO - PROCESS | 26052 | 02:40:08 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39899/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:40:08 INFO - PROCESS | 26052 | RSS: Main: 208289792 02:40:08 INFO - PROCESS | 26052 | 02:40:10 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:40:11 INFO - PROCESS | 26052 | RSS: Main: 212058112 02:40:11 INFO - PROCESS | 26052 | 02:40:13 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:40:14 INFO - PROCESS | 26052 | RSS: Main: 217382912 02:40:14 INFO - PROCESS | 26052 | 02:40:17 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:40:17 INFO - PROCESS | 26052 | RSS: Main: 219439104 02:40:17 INFO - PROCESS | 26052 | 02:40:20 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:40:21 INFO - PROCESS | 26052 | RSS: Main: 219238400 02:40:21 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | Cycle 1(1): loaded http://localhost:39899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39899/tests/kraken/driver.html?testName=ai-astar) 02:40:23 INFO - PROCESS | 26052 | RSS: Main: 265379840 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | __start_tp_report 02:40:23 INFO - PROCESS | 26052 | _x_x_mozilla_page_load 02:40:23 INFO - PROCESS | 26052 | _x_x_mozilla_page_load_details 02:40:23 INFO - PROCESS | 26052 | |i|pagename|runs| 02:40:23 INFO - PROCESS | 26052 | |0;ai-astar;115;109;116;115;115;114;115;115;115;115 02:40:23 INFO - PROCESS | 26052 | |1;audio-beat-detection;158;128;157;209;131;161;159;134;182;134 02:40:23 INFO - PROCESS | 26052 | |2;audio-dft;161;162;162;161;161;161;163;161;162;161 02:40:23 INFO - PROCESS | 26052 | |3;audio-fft;75;76;75;154;75;74;77;75;127;76 02:40:23 INFO - PROCESS | 26052 | |4;audio-oscillator;92;94;144;93;93;95;127;93;93;121 02:40:23 INFO - PROCESS | 26052 | |5;imaging-gaussian-blur;118;117;117;118;117;117;117;117;116;117 02:40:23 INFO - PROCESS | 26052 | |6;imaging-darkroom;146;146;146;146;147;146;146;146;146;146 02:40:23 INFO - PROCESS | 26052 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;94;94 02:40:23 INFO - PROCESS | 26052 | |8;json-parse-financial;65;66;62;63;66;64;63;65;63;64 02:40:23 INFO - PROCESS | 26052 | |9;json-stringify-tinderbox;65;64;64;65;64;64;63;71;64;63 02:40:23 INFO - PROCESS | 26052 | |10;stanford-crypto-aes;82;81;87;84;82;83;83;83;147;84 02:40:23 INFO - PROCESS | 26052 | |11;stanford-crypto-ccm;136;129;129;132;127;127;128;126;146;142 02:40:23 INFO - PROCESS | 26052 | |12;stanford-crypto-pbkdf2;175;168;172;169;171;172;166;172;169;166 02:40:23 INFO - PROCESS | 26052 | |13;stanford-crypto-sha256-iterative;69;66;70;65;67;66;66;66;65;65 02:40:23 INFO - PROCESS | 26052 | __end_tp_report 02:40:23 INFO - PROCESS | 26052 | __start_cc_report 02:40:23 INFO - PROCESS | 26052 | _x_x_mozilla_cycle_collect,2061 02:40:23 INFO - PROCESS | 26052 | __end_cc_report 02:40:23 INFO - PROCESS | 26052 | __startTimestamp1463650823367__endTimestamp 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | ------- Summary: start ------- 02:40:23 INFO - PROCESS | 26052 | Number of tests: 14 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:1.96 (1.7%) stddev-sans-first:2.06 02:40:23 INFO - PROCESS | 26052 | Values: 115.0 109.0 116.0 115.0 115.0 114.0 115.0 115.0 115.0 115.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#1] audio-beat-detection Cycles:10 Average:155.30 Median:158.50 stddev:25.60 (16.1%) stddev-sans-first:27.13 02:40:23 INFO - PROCESS | 26052 | Values: 158.0 128.0 157.0 209.0 131.0 161.0 159.0 134.0 182.0 134.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 02:40:23 INFO - PROCESS | 26052 | Values: 161.0 162.0 162.0 161.0 161.0 161.0 163.0 161.0 162.0 161.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#3] audio-fft Cycles:10 Average:88.40 Median:75.00 stddev:28.20 (37.6%) stddev-sans-first:29.49 02:40:23 INFO - PROCESS | 26052 | Values: 75.0 76.0 75.0 154.0 75.0 74.0 77.0 75.0 127.0 76.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:93.00 stddev:18.93 (20.4%) stddev-sans-first:19.53 02:40:23 INFO - PROCESS | 26052 | Values: 92.0 94.0 144.0 93.0 93.0 95.0 127.0 93.0 93.0 121.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 02:40:23 INFO - PROCESS | 26052 | Values: 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 117.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 02:40:23 INFO - PROCESS | 26052 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 02:40:23 INFO - PROCESS | 26052 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:64.50 stddev:1.37 (2.1%) stddev-sans-first:1.41 02:40:23 INFO - PROCESS | 26052 | Values: 65.0 66.0 62.0 63.0 66.0 64.0 63.0 65.0 63.0 64.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:64.00 stddev:2.31 (3.6%) stddev-sans-first:2.45 02:40:23 INFO - PROCESS | 26052 | Values: 65.0 64.0 64.0 65.0 64.0 64.0 63.0 71.0 64.0 63.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:83.00 stddev:20.23 (24.4%) stddev-sans-first:21.27 02:40:23 INFO - PROCESS | 26052 | Values: 82.0 81.0 87.0 84.0 82.0 83.0 83.0 83.0 147.0 84.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:130.50 stddev:6.92 (5.3%) stddev-sans-first:7.21 02:40:23 INFO - PROCESS | 26052 | Values: 136.0 129.0 129.0 132.0 127.0 127.0 128.0 126.0 146.0 142.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:171.50 stddev:2.91 (1.7%) stddev-sans-first:2.46 02:40:23 INFO - PROCESS | 26052 | Values: 175.0 168.0 172.0 169.0 171.0 172.0 166.0 172.0 169.0 166.0 02:40:23 INFO - PROCESS | 26052 | 02:40:23 INFO - PROCESS | 26052 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.00 stddev:1.72 (2.6%) stddev-sans-first:1.56 02:40:23 INFO - PROCESS | 26052 | Values: 69.0 66.0 70.0 65.0 67.0 66.0 66.0 66.0 65.0 65.0 02:40:23 INFO - PROCESS | 26052 | -------- Summary: end -------- 02:40:23 INFO - PROCESS | 26052 | 02:40:24 INFO - TEST-INFO | 26052: exit 0 02:40:24 INFO - TEST-OK | kraken | took 64561ms 02:40:24 INFO - SUITE-END | took 1182s 02:40:24 INFO - Completed test suite (00:19:42) 02:40:24 INFO - javascript benchmark 02:40:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2620.030588682188, 2645.325442012287, 2650.4169082801777, 2682.2221555748833, 2685.6508653875007, 4557.339185818063, 4665.510121437243, 4679.906401871961, 4717.8112875484985, 4791.592714619257, 4695.436547614283, 4695.882602934928, 4708.576228139466, 4758.143534163849, 4762.904741905159, 4286.678499112563, 4300.35494675798, 4371.519132895395, 4396.450443694538, 4499.842505512308, 4390.2317094508335, 4424.867253982386, 4449.26587113126, 4455.398521199645, 4483.641308695313, 4264.445622069111, 4266.338717498762, 4271.850485233002, 4306.5262821089655, 4308.5045219799495, 4259.74441533509, 4303.311470164758, 4318.201132790444, 4320.546342634041, 4371.978140109279, 4291.0559676871035, 4309.267424537836, 4313.547077556843, 4337.067530480962, 4348.2607956647125, 4536.0, 4643.187442197602, 4690.343351930733, 4700.459447163552, 4711.670183087185, 5157.5616072633875, 5167.844964651066, 5171.5862730981435, 5183.067047931371, 5187.351581052369, 4966.776495057695, 5182.093133701587, 5182.170852663556, 5220.112580861262, 5226.608004399685, 4677.158111539921, 4716.056788642285, 4719.882002949885, 4745.406824146981, 4773.761311934459, 5190.403103643054, 5236.0575096483, 5240.580753539708, 5242.082635538765, 5249.133892907627, 4779.832705855279, 4974.054929563371, 4982.402111746614, 5016.372953380827, 5023.095842748267, 5111.642185047011, 5112.412072611697, 5126.769295381717, 5128.769205385766, 5172.560332371715, 5092.261622064854, 5102.795888164506, 5105.5149760772665, 5108.182690769533, 5152.845414637567, 4821.987382649683, 4856.68431551948, 4951.356323677899, 5063.215201643751, 5075.0, 4282.35764635301, 4290.163418133434, 4300.354946757949, 4334.718243314174, 4345.34819777036, 3740.3454395481226, 3751.362268414376, 3760.1539653577724, 3792.4311353296675, 3801.885943421702, 4266.189424009428, 4288.592583704543, 4301.397804307399, 4316.374125751749, 4357.3899654048455, 4975.353204083446, 5028.748562571856, 5033.194688849767, 5046.470120637354, 5077.111505486525, 4302.096559722493, 4305.182015417061, 4305.418768466279, 4306.569343065668, 4350.521442641307, 4406.449193850769, 4573.199690054227, 4604.263317869125, 4615.076984603093, 4615.630749540029, 4826.0589185108565, 4840.2255639097575, 4854.053459575349, 4855.101806165871, 4883.877903052452, 4962.379702537183, 5147.897042059213, 5157.922631160461, 5159.303494028258, 5212.9739351304515, 4753.168195565693, 4783.689060211075, 4796.112719146969, 4807.95192048075, 4815.831445899376, 5009.9248511271635, 5032.899342013066, 5034.244863270539, 5034.44621091687, 5075.3148324975655, 4903.41159060915, 5056.115179843439, 5061.848144555669, 5072.594192464668, 5130.281760553451, 4927.852164435073, 4970.279309500174, 4994.875128121681, 4997.075541024922, 5041.596672266283, 4969.602431805374, 4989.650724449254, 4989.650724449254, 4992.675476094042, 5039.84880453587, 4793.257045158006, 4843.1524483216, 4864.537868902448, 4908.190148625579, 4970.527799859007, 4948.727819969936, 4964.478729733471, 4975.502449754996, 4976.2286845539, 5006.374203224597, 4864.6594738369395, 4875.414950205998, 4878.146324393288, 4885.780139893643, 4920.458749537476], "value": 4657.043262430417, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7790950484062, 578.5082679722236, 582.6125626458405, 602.400611391665, 610.4810910725884, 40886.38670419942, 42149.57850421495, 42213.15573688532, 42233.36649950253, 46344.304835427494, 43502.34746478798, 43891.56108438923, 43974.560254397446, 43982.56017439825, 44026.55973440265, 7880.527168369902, 8016.759497215093, 8020.438569300151, 8020.478668886532, 8021.558814265213, 7886.132525422199, 7955.0, 7981.082175549799, 7981.082175549828, 7982.880256796153, 8003.559804210766, 8040.115587285394, 8041.0752763432365, 8043.235892590194, 8045.436819422643, 7937.761867143966, 7986.880196797082, 7999.040115186157, 8003.1996800320085, 8003.679852805881, 7954.522728636301, 8017.839643207161, 8022.919770802334, 8023.237792409712, 8025.197480251928, 40391.19217615631, 41056.794716026525, 41154.0, 41159.7942010288, 41227.175456491, 3269.705726484604, 3357.597088349389, 3400.7279417646528, 3864.265789499986, 3936.783476908769, 29759.0, 30145.0956471304, 30304.69695303041, 30737.231569210948, 31359.686403135904, 3224.69365410288, 3231.434498962672, 3243.7405007599573, 3267.2648654052887, 3315.635280119185, 263701.0, 264479.0, 265080.0, 265298.0, 265473.0, 29674.1097767063, 30674.0, 30687.0, 59020.0, 59277.703611481666, 34433.31133377369, 34465.31069378649, 34590.65409345925, 34602.0, 34607.30785384278, 13807.378667959765, 13889.79165312521, 13928.721425571635, 13948.372323245478, 13961.581152565439, 11842.76314473723, 11899.048076153887, 12006.579769708018, 12026.819597706042, 12131.454084566216, 8470.322374210049, 8476.872846907301, 8483.236508714246, 8534.317254619615, 8573.22840944318, 3267.983660081732, 3268.444364458048, 3278.164068162603, 3293.5883014623173, 3336.866525338959, 904.5432056811268, 923.6905636611676, 949.4018768176005, 988.1748739802346, 1041.1722680469045, 28208.29479262994, 29075.56386654202, 29255.56116658252, 29593.26016849553, 29779.0, 999.5502024089044, 1023.8668973033607, 1068.4444089073793, 1085.7285678580356, 1107.2249425402251, 15210.391584336503, 15591.142487163315, 15645.921770391074, 15657.921710391374, 15879.206039697969, 369046.4643030303, 373216.0, 374482.0, 374846.0, 375785.0, 52531.0, 53853.0, 53886.46113538893, 53932.73033634807, 54072.45927540674, 1145.381493993251, 1231.6428235811693, 1272.2620879889641, 1285.1325355385077, 1291.180100636103, 29084.418311633224, 30364.089077327717, 30885.227869303446, 31079.68920310768, 32841.83579082089, 50524.24213636872, 50916.23625645544, 51415.74292128516, 51420.48579514307, 51446.0, 345755.0, 346126.0, 348560.0, 349154.0, 350385.0, 33570.66429335676, 39210.411843822934, 40176.59823401846, 40337.0, 43062.78468607637, 44178.33732493951, 44295.557044429144, 44707.552924470336, 45070.774646126556, 45396.54603454055, 36242.4563631551, 37500.2499949994, 37669.24661506699, 37954.2409151821, 38137.6186238134, 37324.6267537332, 37487.25025499529, 37755.43366849554, 38025.0, 38365.80817096008, 36927.44608830923, 37609.0, 38543.614563854004, 38577.228455431294, 38682.22635547329, 31652.208694782814, 33453.66546334505, 33762.3247535043, 34071.31857362889, 34311.82844085764, 26116.34709132211, 26155.215343539723, 26203.606945895444, 26204.475910481302, 26480.602790957764], "value": 17439.342698013264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.59383459698125, 386.33935969492165, 388.6696308138083, 398.4382021349895, 403.95354534228574, 16419.50741477756, 17845.464636060908, 18807.24771009158, 18809.905950470245, 18827.246910123595, 17582.560435989108, 17939.64120717585, 18058.187381567826, 18412.44762657119, 18419.63160736788, 5105.514976077276, 5225.111731005738, 5231.712255825928, 5250.789968401259, 5283.260343551906, 5171.482851714817, 5492.917606235909, 5504.284443022401, 5551.278333816618, 5578.191162281467, 5539.196816461611, 5547.0, 5548.389677135512, 5557.138643510242, 5643.887122257553, 5888.322842873082, 5997.880042399128, 6012.819615411545, 6023.969880150593, 6026.578139530235, 4674.719516828967, 4979.45226025137, 5324.467553244684, 5325.627206095575, 5328.200769884548, 12588.05589580785, 12852.35738213095, 12968.0, 13054.151480153854, 13128.60614181576, 1912.129980858706, 1914.7893731689503, 1994.2621230144794, 2000.9099590518315, 2016.5261163626535, 9116.453012819185, 9424.387414818015, 9510.191633711143, 9526.90440599333, 9582.90417095827, 1708.103245795967, 1709.7264437690035, 1754.0791084680518, 1779.243821375923, 1795.5062357851564, 69619.65190174017, 71237.0, 71318.0, 71438.0, 71911.28088719098, 10297.227707921937, 10365.792684146425, 10516.316439431412, 10606.363618182933, 10821.350718956888, 14473.348699308557, 14712.55862324132, 14716.926415368069, 14738.705225895423, 14745.041572297767, 7467.7012919483695, 7712.5758083305955, 7740.574268415291, 7827.295543401121, 7832.099308579473, 7368.336849683555, 7375.44684148691, 7487.775366739006, 7520.1351844537485, 7520.1351844537485, 4401.911961760747, 4456.64346852251, 4556.2937744649635, 4594.908101838011, 4607.995521947963, 1815.9818401816076, 1856.3873921605837, 1901.4865986183654, 1937.4575118966711, 1956.403296994402, 914.5838643417362, 935.8736570562887, 944.1455482788087, 958.5351104714208, 999.9700308682069, 17244.137793110294, 17488.65022699539, 17498.737518937225, 17723.379681711078, 17796.46610601684, 903.1826197291462, 906.71891713569, 930.5812384427035, 935.8081593273363, 955.9904400956041, 9861.506924653739, 10003.24975626831, 10069.647562335284, 10279.588816447258, 10340.741481462874, 362363.0, 368716.0, 375492.0, 377961.0, 377985.0, 65331.67334163299, 75971.62014190004, 80387.0, 84118.0, 86690.0, 920.8342498350361, 941.2303723587127, 948.4546385828177, 960.3757557587485, 962.8748262725801, 16554.82324745366, 16645.750313745062, 16689.49931502057, 17018.829811701726, 17194.140292985052, 36917.26165476729, 37450.8127459361, 37839.43240851334, 38892.6110738889, 38966.6103338963, 53728.194077089654, 53967.0, 54849.0, 55475.33573992787, 55538.0, 5792.53659707214, 7763.262490063435, 7770.572618506037, 7974.082980457321, 8057.717979870677, 8765.78085547841, 11632.941835290769, 11808.992471279731, 11860.584879529175, 12011.939940300243, 5555.0278701227935, 7988.880166797619, 9113.544322783968, 10509.0, 10592.205584581034, 10110.848337275094, 10133.594656213669, 10202.795944080928, 10219.233557483367, 10550.103241224582, 10518.684439466828, 10519.94740026295, 10745.623903163352, 10774.919486302644, 11017.559297628006, 6844.349786770249, 7184.6048467334795, 7445.181030086795, 7988.201179881965, 8066.596670166586, 8689.565521724015, 10802.297850639683, 10937.398443085707, 10945.124390048937, 11047.558097676325], "value": 8326.338108173572, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.8956883449325, 1522.7411340072188, 1538.6999535090658, 1703.812580616132, 1873.8313551780336, 7084.114485689289, 7543.547387156767, 7692.961535192316, 7698.230176982306, 7895.249951254622, 7615.77152685419, 7770.456068075223, 7781.844363112735, 7787.64955576999, 7787.9221207787905, 2161.4704397422615, 2183.836212284077, 2187.6937228787983, 2188.0810059774853, 2190.37574291327, 2177.553601511694, 2198.472366632013, 2218.101668824129, 2223.5775202711434, 2224.132588290568, 2167.1331467413, 2175.41263858758, 2185.868847869125, 2189.8029177374033, 2217.168561789329, 2151.075037733774, 2153.83846211534, 2159.7408311002623, 2162.1891790578534, 2164.6211912915255, 2126.064531606088, 2162.664786958024, 2163.588918105555, 2164.2425151197112, 2164.6969424280614, 7633.5419874807685, 7764.301212890867, 7771.0, 7914.0, 8174.509529428194, 4635.884102897421, 4733.95266047342, 4742.596879265267, 4743.549362810528, 4745.786439610191, 3016.8491575421363, 3053.1603808952495, 3088.7683423743083, 3100.5659207710937, 3100.612423447069, 880.4013270975734, 884.3765145572356, 892.312919052326, 893.066745251214, 907.129156010231, 10880.238383313172, 10884.782304353868, 10909.509072091774, 10916.836247456295, 10950.288231265022, 3994.8601798936897, 4076.5719599442223, 4152.896177595524, 4153.709240353207, 4174.269502837052, 8607.78480537979, 8650.178233067849, 8653.740387788377, 8662.740117796477, 8666.436681615674, 4718.221493453541, 4718.740469274223, 4721.220998535272, 4721.976390118096, 4725.3384526166365, 3832.3293423651307, 4129.607687269705, 4229.894252643647, 4273.337632666941, 4299.634531064832, 3352.6814952579953, 3384.4584866421255, 3413.897583072462, 3420.7935881356384, 3456.688897999142, 972.2853702528636, 981.6367943860818, 990.1633120013577, 992.2954702161402, 995.8506224066448, 752.8870669399524, 773.2731232641299, 818.5048045932244, 896.7354630384052, 899.8875140607424, 5226.346706661668, 5340.49265319794, 5370.91943620846, 5394.73026348681, 5431.429699881455, 844.7845799321134, 880.6257340630335, 907.6505545365083, 929.4609126706493, 934.2245935873209, 3581.94627080594, 3713.9071523212137, 3768.4347347898033, 3787.8484860605818, 3862.227554489057, 9893.802123957625, 10080.495975201211, 10193.541290641939, 10211.489425528694, 10218.131458826083, 9988.300818942604, 10080.193584513221, 10099.64651237218, 10106.292559520763, 10122.54448549817, 7215.783526494213, 7638.777795552684, 7681.001469808889, 7729.884051739341, 7732.149463559004, 5284.841454756363, 5396.595255355785, 5482.725863706719, 5515.61390702647, 5543.501084902378, 8917.910820891968, 9276.02601726809, 9302.16280534755, 9321.0, 9431.669891553762, 9391.906080939376, 9473.194778443909, 9498.382605130644, 9503.619855205714, 9511.096445837635, 6212.409821066992, 6808.251092379934, 6899.344562266577, 7158.642067896689, 7307.963460182665, 7344.48588598793, 7363.484556081023, 7421.0723659542555, 7427.368673662799, 7429.702811887464, 6482.416582507597, 6658.167729033871, 6671.33286671329, 6754.223264324665, 6777.6272305023695, 6536.248331441945, 6564.540482166202, 6583.3087525811015, 6631.237407697983, 6667.833304167435, 6024.608400453956, 6029.065494848305, 6057.303410107894, 6060.484858786876, 6064.02975523914, 4268.231718290737, 4313.633341166036, 4363.083752412029, 4365.956340436555, 4388.802503887397, 4499.1451624191295, 4624.768761561976, 4647.326137709945, 4658.464276608098, 4660.20776468008], "value": 4150.3710028635305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.5978739021236, 300.3242703916189, 300.9548567714841, 304.1923692596158, 307.9692030796921, 4839.4918533554, 4844.61243100552, 4855.562999330064, 4863.392075990501, 4888.364512613364, 4797.376341075664, 4806.279058141281, 4807.2789081637775, 4817.638677099214, 4834.734089625069, 3634.4003239465455, 3707.703383729309, 3737.9813100934457, 3786.602406747293, 3806.486124373202, 3768.5100936878166, 3789.2421515696833, 3789.578907909534, 3800.8977396555106, 3803.5055442792536, 3561.5904171020406, 3753.7800214930257, 3766.1526156614677, 3773.6603705666485, 3781.6596506314427, 3632.146445585285, 3775.9433608495897, 3796.8101594920417, 3800.1449673823445, 3810.7523011004196, 3329.51722000311, 3338.232206592473, 3341.148007258158, 3346.1300061983816, 3353.5305057292, 4543.341215523764, 4589.495155532889, 4616.76916154191, 4621.976890115528, 4624.676272660915, 4212.620864122244, 4316.460442444694, 4321.0709697415205, 4323.762193079379, 4329.826806927719, 4958.752062396866, 4964.925526117111, 4974.527419895104, 4982.800687972477, 4985.975070124662, 3614.102596137023, 3627.2020155565733, 3642.979965609607, 3643.0892276930767, 3646.197836475971, 4966.453690094087, 5114.616403769695, 5200.973995130038, 5200.973995130038, 5215.16557350822, 3697.205100903265, 3712.4431335299923, 3716.955674379081, 3726.1802403471193, 3729.384651532521, 3763.9435408468894, 3770.2271034437877, 3770.792606406619, 3775.7356985011334, 3780.962190378061, 4244.936325955113, 4254.127903779718, 4263.253930562139, 4269.210196113729, 4278.486581610165, 4782.402199725035, 4984.626153038536, 4986.675866068694, 4993.550580447777, 4997.3253610762085, 1289.2006955687345, 1297.5004623220127, 1301.947922083106, 1306.5296493262417, 1309.8886594639562, 842.768238734353, 852.6802449081595, 885.2121611765534, 900.0, 904.9230815380766, 4341.82632694695, 4352.630026447724, 4358.847440339573, 4388.341748737651, 4395.912081758347, 5000.324956130876, 5062.190049592047, 5075.63982748024, 5081.8475445736685, 5119.745662312757, 4245.554216807191, 4305.363961694598, 4332.956670433318, 4364.4980827204645, 4397.768624785065, 2692.488427198826, 2710.7259587994004, 2712.986435067812, 2715.4433341165013, 2747.738964798341, 4736.360591320127, 4737.28940658904, 4757.26262429324, 4782.52174782519, 4786.928196077061, 5053.418856831438, 5180.611454140954, 5182.300389447451, 5198.610104242197, 5350.91973620396, 4890.144224760653, 4900.730459824673, 4914.115459217375, 4921.548143297736, 4925.5074492551175, 5189.4032186299055, 5207.411739419514, 5215.217717342429, 5217.895642087061, 5221.712805795718, 4858.392700912386, 5123.846284611468, 5124.897502050013, 5125.231215317732, 5129.948700513097, 4897.4857639947295, 4915.483874193158, 4934.432540257916, 4936.605071594192, 4937.629677774253, 3598.758428342218, 3613.837377317974, 3625.9456108158925, 3632.709383249281, 3639.217568222845, 5034.496550344937, 5170.699038927641, 5204.906969536289, 5210.609204309768, 5217.139172036572, 5008.87477813043, 5029.396472423333, 5033.597312214941, 5041.747912604282, 5045.9243111352625, 5006.349174607278, 5026.59787217029, 5037.874053148701, 5078.4159821619505, 5095.4395016547405, 4756.191447453872, 4930.728809915346, 4947.950520494749, 5014.824481143142, 5026.321446604661, 4864.713584615225, 4896.730679812645, 5022.422421421583, 5044.949550504448, 5070.340855688737, 4897.730624815669, 4904.288878112582, 4952.380952380952, 4952.702837829742, 4974.801007959786], "value": 3791.977228626654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.9490038247131, 716.2372073741468, 802.4022103532869, 805.5448671500602, 813.2263612594073, 12478.126531142812, 12913.806292905614, 12961.805572916413, 12967.805482917753, 12987.090903636748, 13055.151415158009, 13068.673283167926, 13105.80341294879, 13109.082364234508, 13110.27893465859, 661.5170925224589, 831.9001719793603, 849.1847826086948, 862.65062649627, 873.7422460374205, 858.3690987124489, 859.2009431228954, 862.4738909265343, 862.65062649627, 863.0592654007131, 920.0477505781517, 920.0753242990785, 920.3189639666665, 920.7652048727565, 920.8664743612172, 698.5284932670453, 882.1487264789445, 908.8000639859221, 909.8771665825095, 910.0217266438572, 676.6447615002163, 676.7090151234967, 676.7462201674372, 677.3395938959525, 677.5832862789398, 12485.375731213493, 12596.370181490889, 12596.496140154382, 12600.495980160782, 12624.873751262554, 11721.0, 12070.517179312817, 12156.45295961675, 12452.875471245263, 12684.936575317064, 10890.727731806688, 10968.0, 10992.0, 10994.230403871732, 11410.20128590999, 4177.728447650893, 4280.850170244058, 4323.0, 4381.561843815625, 4389.582989615981, 44721.32918006233, 45195.77402113001, 45218.095638087056, 45294.0, 45433.77283113563, 10300.278980471296, 10464.0, 10566.366018038942, 10583.894161058292, 10991.120710343153, 16723.331066757193, 18891.055447227583, 18906.243750249836, 18927.432177034712, 18937.90531047336, 7058.435325173974, 7236.782896513113, 7311.817204569822, 7331.04696389466, 7435.442341824277, 3938.8030598469963, 3992.8203230854683, 4033.3278681194574, 4048.4534587830854, 4129.215449064668, 2822.2662107852107, 2904.607877936494, 2905.6077429547163, 2910.0833237530533, 2910.5052141136057, 516.302990962198, 532.5792623827133, 532.8987492376436, 543.8015124479537, 581.6016029020135, 575.9164921086419, 588.0825911577953, 599.598269159664, 604.5224272824507, 608.7321578505444, 7749.612519374009, 7833.216678332121, 7864.921350786533, 7882.763517094496, 8179.0, 547.2584647802171, 591.1546488521369, 604.4378727783204, 613.8520965793994, 626.9874602507924, 3666.6185352446764, 3692.630736926286, 3753.4932784074344, 3781.6785573226034, 3798.2973149967343, 58541.4145858536, 60566.394336056954, 60736.0, 60736.69631651902, 60753.924607539775, 43273.0, 44556.77721611371, 48306.0, 49858.50141498611, 51341.22988155181, 42191.0, 47753.28370074452, 48053.51946480491, 48566.27150592744, 49055.75472122688, 8352.540610266493, 8502.702405416028, 8628.956855215683, 8644.660271306442, 8774.048056198835, 31002.379952400373, 31259.84370078135, 31453.68546314599, 31483.685163148075, 32318.192045198117, 38451.230975379774, 39523.60476395199, 42472.0, 42632.0, 42968.14063718771, 11116.833247501456, 11153.107751379708, 11209.327440353605, 11215.831762523732, 11262.0, 11737.471813768572, 12004.39978001079, 12364.0, 12381.257124572554, 12770.744585108432, 11349.432528373713, 11696.649100526998, 11956.043516518832, 12288.262704237774, 12307.26156430617, 11531.942340288246, 11727.179097463437, 11756.647300580997, 12114.697132571404, 12181.573644922739, 11253.235267665284, 11270.887291126985, 11338.263012904135, 11509.884901150881, 11760.88239117598, 9436.103570160823, 9512.626540217932, 9771.511424428609, 9830.213582913493, 10191.694249172537, 9682.661106861508, 9690.12788849007, 9816.607335706492, 9829.0, 9829.950850245703], "value": 6262.889080029817, "unit": "score"}], "name": "dromaeo_css", "value": 6367.640906277042, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 116.0, 115.0, 115.0, 114.0, 115.0, 115.0, 115.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 128.0, 157.0, 209.0, 131.0, 161.0, 159.0, 134.0, 182.0, 134.0], "value": 155.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 161.0, 161.0, 163.0, 161.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 154.0, 75.0, 74.0, 77.0, 75.0, 127.0, 76.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 144.0, 93.0, 93.0, 95.0, 127.0, 93.0, 93.0, 121.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 62.0, 63.0, 66.0, 64.0, 63.0, 65.0, 63.0, 64.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 64.0, 64.0, 63.0, 71.0, 64.0, 63.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 87.0, 84.0, 82.0, 83.0, 83.0, 83.0, 147.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 129.0, 129.0, 132.0, 127.0, 127.0, 128.0, 126.0, 146.0, 142.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 168.0, 172.0, 169.0, 171.0, 172.0, 166.0, 172.0, 169.0, 166.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 70.0, 65.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1569.1000000000001, "alertThreshold": 2.0}]} 02:40:24 INFO - Return code: 0 02:40:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:40:24 ERROR - # TBPL SUCCESS # 02:40:24 INFO - Running post-action listener: _resource_record_post_action 02:40:24 INFO - ##### 02:40:24 INFO - ##### Finished run-tests step (success) 02:40:24 INFO - ##### 02:40:24 INFO - Running post-run listener: _resource_record_post_run 02:40:24 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 538943488; Read time: 12; Write time: 480684 02:40:24 INFO - TinderboxPrint: CPU usage
13.1% 02:40:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 02:40:24 INFO - TinderboxPrint: I/O write bytes / time
538,943,488 / 480,684 02:40:24 INFO - TinderboxPrint: CPU idle
8,325.3 (86.9%) 02:40:24 INFO - TinderboxPrint: CPU user
1,210.9 (12.6%) 02:40:24 INFO - TinderboxPrint: Swap in / out
0 / 0 02:40:24 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 243314688; Read time: 0; Write time: 273960 02:40:24 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295628800; Read time: 12; Write time: 206724 02:40:25 INFO - Running post-run listener: _upload_blobber_files 02:40:25 INFO - Blob upload gear active. 02:40:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:40:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:40:25 INFO - (blobuploader) - INFO - Open directory for files ... 02:40:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:40:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:40:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:26 INFO - (blobuploader) - INFO - Done attempting. 02:40:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:40:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:40:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:27 INFO - (blobuploader) - INFO - Done attempting. 02:40:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:40:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:40:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:31 INFO - (blobuploader) - INFO - Done attempting. 02:40:31 INFO - (blobuploader) - INFO - Iteration through files over. 02:40:31 INFO - Return code: 0 02:40:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:40:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:40:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06aebac1d7c8da8a8cfb65c70e51169e5368037edc5ae6201db33ac8051a308330cf92b618e2dd11760d03df1520a602a855d1a776bb73a19a19a4f67bdd8298", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ce01d5a88ed1ca712a0a4f599a45edd9031fda15fc515d50dbccf5cd39e1f6c7ab53a11023f5949534183d8740d72fb4c9d9611eef3bed8bcde11e051017100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/711d52aa38e20cdc1b8f309cbcfec8a63fac6a906dfdb5713c2d21d6e6d78a39063c934c6ee213635b600cc5ed4ed7f148e2430e9fac6c6e6e410456578435e6"} 02:40:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:40:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:40:31 INFO - Contents: 02:40:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06aebac1d7c8da8a8cfb65c70e51169e5368037edc5ae6201db33ac8051a308330cf92b618e2dd11760d03df1520a602a855d1a776bb73a19a19a4f67bdd8298", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ce01d5a88ed1ca712a0a4f599a45edd9031fda15fc515d50dbccf5cd39e1f6c7ab53a11023f5949534183d8740d72fb4c9d9611eef3bed8bcde11e051017100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/711d52aa38e20cdc1b8f309cbcfec8a63fac6a906dfdb5713c2d21d6e6d78a39063c934c6ee213635b600cc5ed4ed7f148e2430e9fac6c6e6e410456578435e6"} 02:40:31 INFO - Running post-run listener: copy_logs_to_upload_dir 02:40:31 INFO - Copying logs to upload dir... 02:40:31 INFO - mkdir: /builds/slave/test/build/upload/logs 02:40:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.770905 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-05-19 02:40:31.921570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 02:40:31.924931) ========= 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=0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06aebac1d7c8da8a8cfb65c70e51169e5368037edc5ae6201db33ac8051a308330cf92b618e2dd11760d03df1520a602a855d1a776bb73a19a19a4f67bdd8298", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ce01d5a88ed1ca712a0a4f599a45edd9031fda15fc515d50dbccf5cd39e1f6c7ab53a11023f5949534183d8740d72fb4c9d9611eef3bed8bcde11e051017100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/711d52aa38e20cdc1b8f309cbcfec8a63fac6a906dfdb5713c2d21d6e6d78a39063c934c6ee213635b600cc5ed4ed7f148e2430e9fac6c6e6e410456578435e6"} build_url:https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/GAV4o0vHSny44V3k-nOFtg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06aebac1d7c8da8a8cfb65c70e51169e5368037edc5ae6201db33ac8051a308330cf92b618e2dd11760d03df1520a602a855d1a776bb73a19a19a4f67bdd8298", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ce01d5a88ed1ca712a0a4f599a45edd9031fda15fc515d50dbccf5cd39e1f6c7ab53a11023f5949534183d8740d72fb4c9d9611eef3bed8bcde11e051017100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/711d52aa38e20cdc1b8f309cbcfec8a63fac6a906dfdb5713c2d21d6e6d78a39063c934c6ee213635b600cc5ed4ed7f148e2430e9fac6c6e6e410456578435e6"}' ========= master_lag: 0.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 02:40:32.075119) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:40:32.075720) ========= 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=0333a256a5e4f052382dd180000001b1-1463649575.609349-524889884 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 02:40:32.127896) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 02:40:32.128334) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 02:40:32.128695) ========= ========= Total master_lag: 0.61 =========