builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1459934531.31 results: success (0) buildid: 20160406014606 builduid: 6d6fb57768674a1485849a8203d327d7 revision: 67ce7164cbb233cd2852a5785fa384837f3f0788 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.314410) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.315029) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.315333) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006387 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.339671) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.339958) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.381777) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.382039) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.433089) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.433402) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.433760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.434035) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-06 02:22:11-- 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% 113M=0s 2016-04-06 02:22:12 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098145 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.544551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.544870) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022621 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.627106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:11.627447) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 67ce7164cbb233cd2852a5785fa384837f3f0788 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 67ce7164cbb233cd2852a5785fa384837f3f0788 --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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-06 02:22:12,166 truncating revision to first 12 chars 2016-04-06 02:22:12,166 Setting DEBUG logging. 2016-04-06 02:22:12,166 attempt 1/10 2016-04-06 02:22:12,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/67ce7164cbb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-06 02:22:12,509 unpacking tar archive at: fx-team-67ce7164cbb2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619951 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:12.260529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:12.260884) ========= echo 67ce7164cbb233cd2852a5785fa384837f3f0788 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'67ce7164cbb233cd2852a5785fa384837f3f0788'] 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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 67ce7164cbb233cd2852a5785fa384837f3f0788 program finished with exit code 0 elapsedTime=0.004750 script_repo_revision: '67ce7164cbb233cd2852a5785fa384837f3f0788' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:12.308998) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:12.309289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-06 02:22:12.318948) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-06 02:22:12.319220) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:22:12 INFO - MultiFileLogger online at 20160406 02:22:12 in /builds/slave/test 02:22:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 02:22:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:22:12 INFO - {'append_to_log': False, 02:22:12 INFO - 'base_work_dir': '/builds/slave/test', 02:22:12 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 02:22:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:22:12 INFO - 'branch': 'Fx-Team-Non-PGO', 02:22:12 INFO - 'buildbot_json_path': 'buildprops.json', 02:22:12 INFO - 'config_files': ('talos/linux_config.py',), 02:22:12 INFO - 'default_actions': ('clobber', 02:22:12 INFO - 'read-buildbot-config', 02:22:12 INFO - 'download-and-extract', 02:22:12 INFO - 'populate-webroot', 02:22:12 INFO - 'create-virtualenv', 02:22:12 INFO - 'install', 02:22:12 INFO - 'run-tests'), 02:22:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:22:12 INFO - 'download_minidump_stackwalk': True, 02:22:12 INFO - 'download_symbols': 'ondemand', 02:22:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:22:12 INFO - 'tooltool.py': '/tools/tooltool.py', 02:22:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:22:12 INFO - '/tools/misc-python/virtualenv.py')}, 02:22:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:22:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:22:12 INFO - 'installer_path': 'installer.exe', 02:22:12 INFO - 'log_level': 'info', 02:22:12 INFO - 'log_name': 'talos', 02:22:12 INFO - 'log_to_console': True, 02:22:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:22:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:22:12 INFO - 'opt_config_files': (), 02:22:12 INFO - 'pip_index': False, 02:22:12 INFO - 'sps_profile': False, 02:22:12 INFO - 'sps_profile_interval': 0, 02:22:12 INFO - 'suite': 'dromaeojs', 02:22:12 INFO - 'system_bits': '32', 02:22:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:22:12 INFO - 'title': 'talos-linux64-ix-007', 02:22:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:22:12 INFO - 'use_talos_json': True, 02:22:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:22:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:22:12 INFO - 'work_dir': 'build'} 02:22:12 INFO - ##### 02:22:12 INFO - ##### Running clobber step. 02:22:12 INFO - ##### 02:22:12 INFO - Running pre-action listener: _resource_record_pre_action 02:22:12 INFO - Running main action method: clobber 02:22:12 INFO - rmtree: /builds/slave/test/build 02:22:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:22:13 INFO - Running post-action listener: _resource_record_post_action 02:22:13 INFO - ##### 02:22:13 INFO - ##### Running read-buildbot-config step. 02:22:13 INFO - ##### 02:22:13 INFO - Running pre-action listener: _resource_record_pre_action 02:22:13 INFO - Running main action method: read_buildbot_config 02:22:13 INFO - Using buildbot properties: 02:22:13 INFO - { 02:22:13 INFO - "project": "", 02:22:13 INFO - "product": "firefox", 02:22:13 INFO - "script_repo_revision": "production", 02:22:13 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:22:13 INFO - "repository": "", 02:22:13 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 02:22:13 INFO - "buildid": "20160406014606", 02:22:13 INFO - "pgo_build": "False", 02:22:13 INFO - "basedir": "/builds/slave/test", 02:22:13 INFO - "buildnumber": 2767, 02:22:13 INFO - "slavename": "talos-linux64-ix-007", 02:22:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:22:13 INFO - "platform": "ubuntu64_hw", 02:22:13 INFO - "scheduler": "tests-fx-team-linux64-talos", 02:22:13 INFO - "branch": "fx-team", 02:22:13 INFO - "repo_path": "integration/fx-team", 02:22:13 INFO - "revision": "67ce7164cbb233cd2852a5785fa384837f3f0788", 02:22:13 INFO - "stage_platform": "linux64", 02:22:13 INFO - "builduid": "6d6fb57768674a1485849a8203d327d7", 02:22:13 INFO - "slavebuilddir": "test" 02:22:13 INFO - } 02:22:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 02:22:13 INFO - Running post-action listener: _resource_record_post_action 02:22:13 INFO - ##### 02:22:13 INFO - ##### Running download-and-extract step. 02:22:13 INFO - ##### 02:22:13 INFO - Running pre-action listener: _resource_record_pre_action 02:22:13 INFO - Running main action method: download_and_extract 02:22:13 INFO - mkdir: /builds/slave/test/build/tests 02:22:13 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:22:13 INFO - https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:22:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 02:22:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 02:22:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 02:22:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:22:18 INFO - Downloaded 1361 bytes. 02:22:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 02:22:18 INFO - Using the following test package requirements: 02:22:18 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 02:22:18 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:22:18 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:22:18 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'jsshell-linux-x86_64.zip'], 02:22:18 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:22:18 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 02:22:18 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:22:18 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:22:18 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:22:18 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 02:22:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:22:18 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:22: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:22:18 INFO - https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:22:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 02:22:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 02:22:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 02:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:22:20 INFO - Downloaded 22125485 bytes. 02:22:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:22:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:22:21 INFO - Return code: 0 02:22:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:22: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:22:21 INFO - https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:22:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 02:22:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 02:22:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 02:22:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:22:22 INFO - Downloaded 22125485 bytes. 02:22:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:22:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:22:24 INFO - Return code: 0 02:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:24 INFO - https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:22:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 02:22:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 02:22:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 02:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:22:25 INFO - Downloaded 10847584 bytes. 02:22:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:22:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:22:25 INFO - Return code: 0 02:22:25 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:22:25 INFO - https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:22:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 02:22:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 02:22:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 02:22:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:22:27 INFO - Downloaded 54094197 bytes. 02:22:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 02:22:27 INFO - mkdir: /builds/slave/test/properties 02:22:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:22:27 INFO - Writing to file /builds/slave/test/properties/build_url 02:22:27 INFO - Contents: 02:22:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 02:22:28 INFO - Running post-action listener: _resource_record_post_action 02:22:28 INFO - Running post-action listener: set_extra_try_arguments 02:22:28 INFO - ##### 02:22:28 INFO - ##### Running populate-webroot step. 02:22:28 INFO - ##### 02:22:28 INFO - Running pre-action listener: _resource_record_pre_action 02:22:28 INFO - Running main action method: populate_webroot 02:22:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:22:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:22:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:22:28 INFO - '--tppagecycles', 02:22:28 INFO - '7'], 02:22:28 INFO - 'tests': ['tsvgm']}}, 02:22:28 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 02:22:28 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:22:28 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 02:22:28 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:22:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:22:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:22:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'tests': ['damp', 'tps']}, 02:22:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['damp', 'tps']}, 02:22:28 INFO - 'g3': {'tests': ['dromaeo_dom']}, 02:22:28 INFO - 'g3-e10s': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['dromaeo_dom']}, 02:22:28 INFO - 'other': {'tests': ['a11yr', 02:22:28 INFO - 'ts_paint', 02:22:28 INFO - 'tpaint', 02:22:28 INFO - 'sessionrestore', 02:22:28 INFO - 'sessionrestore_no_auto_restore', 02:22:28 INFO - 'tabpaint']}, 02:22:28 INFO - 'other-e10s': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['a11yr', 02:22:28 INFO - 'ts_paint', 02:22:28 INFO - 'tpaint', 02:22:28 INFO - 'sessionrestore', 02:22:28 INFO - 'sessionrestore_no_auto_restore', 02:22:28 INFO - 'tabpaint']}, 02:22:28 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['a11yr', 02:22:28 INFO - 'ts_paint', 02:22:28 INFO - 'tpaint', 02:22:28 INFO - 'sessionrestore', 02:22:28 INFO - 'sessionrestore_no_auto_restore', 02:22:28 INFO - 'tabpaint']}, 02:22:28 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['a11yr', 02:22:28 INFO - 'ts_paint', 02:22:28 INFO - 'tpaint', 02:22:28 INFO - 'sessionrestore', 02:22:28 INFO - 'sessionrestore_no_auto_restore', 02:22:28 INFO - 'tabpaint']}, 02:22:28 INFO - 'other_l64': {'tests': ['a11yr', 02:22:28 INFO - 'ts_paint', 02:22:28 INFO - 'tpaint', 02:22:28 INFO - 'sessionrestore', 02:22:28 INFO - 'sessionrestore_no_auto_restore', 02:22:28 INFO - 'tabpaint']}, 02:22:28 INFO - 'other_nol64': {'tests': ['a11yr', 02:22:28 INFO - 'ts_paint', 02:22:28 INFO - 'tpaint', 02:22:28 INFO - 'sessionrestore', 02:22:28 INFO - 'sessionrestore_no_auto_restore', 02:22:28 INFO - 'tabpaint']}, 02:22:28 INFO - 'svgr': {'tests': ['tsvgx', 02:22:28 INFO - 'tsvgr_opacity', 02:22:28 INFO - 'tart', 02:22:28 INFO - 'tscrollx', 02:22:28 INFO - 'cart']}, 02:22:28 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['tsvgx', 02:22:28 INFO - 'tsvgr_opacity', 02:22:28 INFO - 'tart', 02:22:28 INFO - 'tscrollx', 02:22:28 INFO - 'cart']}, 02:22:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'tests': ['tp5o']}, 02:22:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'talos_options': ['--e10s'], 02:22:28 INFO - 'tests': ['tp5o']}, 02:22:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'talos_options': ['--xperf_path', 02:22:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:22:28 INFO - 'tests': ['tp5n']}, 02:22:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:28 INFO - 'talos_options': ['--e10s', 02:22:28 INFO - '--xperf_path', 02:22:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:22:28 INFO - 'tests': ['tp5n']}}, 02:22:28 INFO - 'talos.zip': {'path': '', 02:22:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:22:28 INFO - Running post-action listener: _resource_record_post_action 02:22:28 INFO - ##### 02:22:28 INFO - ##### Running create-virtualenv step. 02:22:28 INFO - ##### 02:22:28 INFO - Running pre-action listener: _resource_record_pre_action 02:22:28 INFO - Running main action method: create_virtualenv 02:22:28 INFO - Creating virtualenv /builds/slave/test/build/venv 02:22:28 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:22:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:22:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:22:28 INFO - Using real prefix '/usr' 02:22:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:22:29 INFO - Installing distribute.............................................................................................................................................................................................done. 02:22:31 INFO - Installing pip.................done. 02:22:31 INFO - Return code: 0 02:22:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:31 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:31 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:22:31 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:22:31 INFO - Using env: {'DISPLAY': ':0', 02:22:31 INFO - 'HOME': '/home/cltbld', 02:22:31 INFO - 'LANG': 'en_US.UTF-8', 02:22:31 INFO - 'LANGUAGE': 'en_US:en', 02:22:31 INFO - 'LOGNAME': 'cltbld', 02:22:31 INFO - 'MAIL': '/var/mail/cltbld', 02:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:31 INFO - 'MOZ_NO_REMOTE': '1', 02:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:31 INFO - 'NO_EM_RESTART': '1', 02:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:31 INFO - 'PWD': '/builds/slave/test', 02:22:31 INFO - 'SHELL': '/bin/bash', 02:22:31 INFO - 'SHLVL': '1', 02:22:31 INFO - 'TERM': 'linux', 02:22:31 INFO - 'TMOUT': '86400', 02:22:31 INFO - 'USER': 'cltbld', 02:22:31 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:31 INFO - '_': '/tools/buildbot/bin/python'} 02:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:31 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:22:31 INFO - Cleaning up... 02:22:31 INFO - Return code: 0 02:22:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:31 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:31 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>=0.7.1'] in /builds/slave/test/build 02:22:31 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>=0.7.1 02:22:31 INFO - Using env: {'DISPLAY': ':0', 02:22:31 INFO - 'HOME': '/home/cltbld', 02:22:31 INFO - 'LANG': 'en_US.UTF-8', 02:22:31 INFO - 'LANGUAGE': 'en_US:en', 02:22:31 INFO - 'LOGNAME': 'cltbld', 02:22:31 INFO - 'MAIL': '/var/mail/cltbld', 02:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:31 INFO - 'MOZ_NO_REMOTE': '1', 02:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:31 INFO - 'NO_EM_RESTART': '1', 02:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:31 INFO - 'PWD': '/builds/slave/test', 02:22:31 INFO - 'SHELL': '/bin/bash', 02:22:31 INFO - 'SHLVL': '1', 02:22:31 INFO - 'TERM': 'linux', 02:22:31 INFO - 'TMOUT': '86400', 02:22:31 INFO - 'USER': 'cltbld', 02:22:31 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:31 INFO - '_': '/tools/buildbot/bin/python'} 02:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:31 INFO - Downloading/unpacking psutil>=0.7.1 02:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:22:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:33 INFO - Installing collected packages: psutil 02:22:33 INFO - Running setup.py install for psutil 02:22:33 INFO - building 'psutil._psutil_linux' extension 02:22:33 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:22:33 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:22:33 INFO - building 'psutil._psutil_posix' extension 02:22:33 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:22:34 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:22:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:34 INFO - Successfully installed psutil 02:22:34 INFO - Cleaning up... 02:22:34 INFO - Return code: 0 02:22:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:22:34 INFO - Using env: {'DISPLAY': ':0', 02:22:34 INFO - 'HOME': '/home/cltbld', 02:22:34 INFO - 'LANG': 'en_US.UTF-8', 02:22:34 INFO - 'LANGUAGE': 'en_US:en', 02:22:34 INFO - 'LOGNAME': 'cltbld', 02:22:34 INFO - 'MAIL': '/var/mail/cltbld', 02:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:34 INFO - 'MOZ_NO_REMOTE': '1', 02:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:34 INFO - 'NO_EM_RESTART': '1', 02:22:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:34 INFO - 'PWD': '/builds/slave/test', 02:22:34 INFO - 'SHELL': '/bin/bash', 02:22:34 INFO - 'SHLVL': '1', 02:22:34 INFO - 'TERM': 'linux', 02:22:34 INFO - 'TMOUT': '86400', 02:22:34 INFO - 'USER': 'cltbld', 02:22:34 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:34 INFO - '_': '/tools/buildbot/bin/python'} 02:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:22:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:22:36 INFO - Installing collected packages: mozsystemmonitor 02:22:36 INFO - Running setup.py install for mozsystemmonitor 02:22:36 INFO - Successfully installed mozsystemmonitor 02:22:36 INFO - Cleaning up... 02:22:36 INFO - Return code: 0 02:22:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:36 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:36 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:22:36 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:22:36 INFO - Using env: {'DISPLAY': ':0', 02:22:36 INFO - 'HOME': '/home/cltbld', 02:22:36 INFO - 'LANG': 'en_US.UTF-8', 02:22:36 INFO - 'LANGUAGE': 'en_US:en', 02:22:36 INFO - 'LOGNAME': 'cltbld', 02:22:36 INFO - 'MAIL': '/var/mail/cltbld', 02:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:36 INFO - 'MOZ_NO_REMOTE': '1', 02:22:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:36 INFO - 'NO_EM_RESTART': '1', 02:22:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:36 INFO - 'PWD': '/builds/slave/test', 02:22:36 INFO - 'SHELL': '/bin/bash', 02:22:36 INFO - 'SHLVL': '1', 02:22:36 INFO - 'TERM': 'linux', 02:22:36 INFO - 'TMOUT': '86400', 02:22:36 INFO - 'USER': 'cltbld', 02:22:36 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:36 INFO - '_': '/tools/buildbot/bin/python'} 02:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:36 INFO - Downloading/unpacking blobuploader==1.2.4 02:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:38 INFO - Downloading blobuploader-1.2.4.tar.gz 02:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:22:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:22:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:22:39 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:22:39 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:22:39 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:22:39 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:22:39 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:22:39 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:22:39 INFO - Downloading docopt-0.6.1.tar.gz 02:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:22:39 INFO - Installing collected packages: blobuploader, requests, docopt 02:22:39 INFO - Running setup.py install for blobuploader 02:22:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:22:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:22:39 INFO - Running setup.py install for requests 02:22:39 INFO - Running setup.py install for docopt 02:22:39 INFO - Successfully installed blobuploader requests docopt 02:22:39 INFO - Cleaning up... 02:22:39 INFO - Return code: 0 02:22:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:39 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:39 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:22:39 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:22:39 INFO - Using env: {'DISPLAY': ':0', 02:22:39 INFO - 'HOME': '/home/cltbld', 02:22:39 INFO - 'LANG': 'en_US.UTF-8', 02:22:39 INFO - 'LANGUAGE': 'en_US:en', 02:22:39 INFO - 'LOGNAME': 'cltbld', 02:22:39 INFO - 'MAIL': '/var/mail/cltbld', 02:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:39 INFO - 'MOZ_NO_REMOTE': '1', 02:22:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:39 INFO - 'NO_EM_RESTART': '1', 02:22:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:39 INFO - 'PWD': '/builds/slave/test', 02:22:39 INFO - 'SHELL': '/bin/bash', 02:22:39 INFO - 'SHLVL': '1', 02:22:39 INFO - 'TERM': 'linux', 02:22:39 INFO - 'TMOUT': '86400', 02:22:39 INFO - 'USER': 'cltbld', 02:22:39 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:39 INFO - '_': '/tools/buildbot/bin/python'} 02:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:40 INFO - Running setup.py (path:/tmp/pip-gi5Pqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:40 INFO - Running setup.py (path:/tmp/pip-OZJu5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:40 INFO - Running setup.py (path:/tmp/pip-P6Kjzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:40 INFO - Running setup.py (path:/tmp/pip-dc7wvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:40 INFO - Running setup.py (path:/tmp/pip-lfU6Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:40 INFO - Running setup.py (path:/tmp/pip-Ds6nu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:40 INFO - Running setup.py (path:/tmp/pip-zaIYTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:40 INFO - Running setup.py (path:/tmp/pip-GQbD3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:40 INFO - Running setup.py (path:/tmp/pip-xm2kF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:40 INFO - Running setup.py (path:/tmp/pip-iHzGka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:40 INFO - Running setup.py (path:/tmp/pip-3KTdcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:40 INFO - Running setup.py (path:/tmp/pip-SCXAX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:40 INFO - Running setup.py (path:/tmp/pip-tSg5xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:41 INFO - Running setup.py (path:/tmp/pip-6ssLhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:41 INFO - Running setup.py (path:/tmp/pip-AnsBri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:41 INFO - Running setup.py (path:/tmp/pip-R639MA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:41 INFO - Running setup.py (path:/tmp/pip-kvA2c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:22:41 INFO - Running setup.py install for manifestparser 02:22:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:22:41 INFO - Running setup.py install for mozcrash 02:22:41 INFO - Running setup.py install for mozdebug 02:22:41 INFO - Running setup.py install for mozdevice 02:22:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:22:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:22:41 INFO - Running setup.py install for mozfile 02:22:41 INFO - Running setup.py install for mozhttpd 02:22:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:22:41 INFO - Running setup.py install for mozinfo 02:22:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Running setup.py install for mozInstall 02:22:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Running setup.py install for mozleak 02:22:42 INFO - Running setup.py install for mozlog 02:22:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Running setup.py install for moznetwork 02:22:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Running setup.py install for mozprocess 02:22:42 INFO - Running setup.py install for mozprofile 02:22:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Running setup.py install for mozrunner 02:22:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:22:42 INFO - Running setup.py install for mozscreenshot 02:22:42 INFO - Running setup.py install for moztest 02:22:43 INFO - Running setup.py install for mozversion 02:22:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:22:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:22:43 INFO - Cleaning up... 02:22:43 INFO - Return code: 0 02:22:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:43 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:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:43 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:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:43 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:43 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:22:43 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:22:43 INFO - Using env: {'DISPLAY': ':0', 02:22:43 INFO - 'HOME': '/home/cltbld', 02:22:43 INFO - 'LANG': 'en_US.UTF-8', 02:22:43 INFO - 'LANGUAGE': 'en_US:en', 02:22:43 INFO - 'LOGNAME': 'cltbld', 02:22:43 INFO - 'MAIL': '/var/mail/cltbld', 02:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:43 INFO - 'MOZ_NO_REMOTE': '1', 02:22:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:43 INFO - 'NO_EM_RESTART': '1', 02:22:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:43 INFO - 'PWD': '/builds/slave/test', 02:22:43 INFO - 'SHELL': '/bin/bash', 02:22:43 INFO - 'SHLVL': '1', 02:22:43 INFO - 'TERM': 'linux', 02:22:43 INFO - 'TMOUT': '86400', 02:22:43 INFO - 'USER': 'cltbld', 02:22:43 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:43 INFO - '_': '/tools/buildbot/bin/python'} 02:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:43 INFO - Running setup.py (path:/tmp/pip-jMdquO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:43 INFO - Running setup.py (path:/tmp/pip-hffiF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:43 INFO - Running setup.py (path:/tmp/pip-BvWGRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:43 INFO - Running setup.py (path:/tmp/pip-DGTa1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:43 INFO - Running setup.py (path:/tmp/pip-ttP8iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:43 INFO - Running setup.py (path:/tmp/pip-ZiEd7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:43 INFO - Running setup.py (path:/tmp/pip-AInAIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:43 INFO - Running setup.py (path:/tmp/pip-bLJmUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:43 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:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:43 INFO - Running setup.py (path:/tmp/pip-NdKY00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:44 INFO - Running setup.py (path:/tmp/pip-bjCfYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:44 INFO - Running setup.py (path:/tmp/pip-3JXAzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:44 INFO - Running setup.py (path:/tmp/pip-ufKi5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:44 INFO - Running setup.py (path:/tmp/pip-SkYOXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:44 INFO - Running setup.py (path:/tmp/pip-2sJU4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:44 INFO - Running setup.py (path:/tmp/pip-JrGgO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:44 INFO - Running setup.py (path:/tmp/pip-JwqQXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:44 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:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:44 INFO - Running setup.py (path:/tmp/pip-lc8o2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:44 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:22:44 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:22:44 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:22:44 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:22:44 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:22:44 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:22:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:22:44 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:22:44 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:22:44 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:22:44 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:22:44 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:22:44 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:22:46 INFO - Downloading blessings-1.6.tar.gz 02:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:22:46 INFO - Installing collected packages: blessings 02:22:46 INFO - Running setup.py install for blessings 02:22:46 INFO - Successfully installed blessings 02:22:46 INFO - Cleaning up... 02:22:46 INFO - Return code: 0 02:22:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:22:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:46 INFO - Reading from file tmpfile_stdout 02:22:46 INFO - Current package versions: 02:22:46 INFO - argparse == 1.2.1 02:22:46 INFO - blessings == 1.6 02:22:46 INFO - blobuploader == 1.2.4 02:22:46 INFO - docopt == 0.6.1 02:22:46 INFO - manifestparser == 1.1 02:22:46 INFO - mozInstall == 1.12 02:22:46 INFO - mozcrash == 0.17 02:22:46 INFO - mozdebug == 0.1 02:22:46 INFO - mozdevice == 0.48 02:22:46 INFO - mozfile == 1.2 02:22:46 INFO - mozhttpd == 0.7 02:22:46 INFO - mozinfo == 0.9 02:22:46 INFO - mozleak == 0.1 02:22:46 INFO - mozlog == 3.1 02:22:46 INFO - moznetwork == 0.27 02:22:46 INFO - mozprocess == 0.22 02:22:46 INFO - mozprofile == 0.28 02:22:46 INFO - mozrunner == 6.11 02:22:46 INFO - mozscreenshot == 0.1 02:22:46 INFO - mozsystemmonitor == 0.0 02:22:46 INFO - moztest == 0.7 02:22:46 INFO - mozversion == 1.4 02:22:46 INFO - psutil == 3.1.1 02:22:46 INFO - requests == 1.2.3 02:22:46 INFO - wsgiref == 0.1.2 02:22:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:46 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:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:46 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:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:46 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:46 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:22:46 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:22:46 INFO - Using env: {'DISPLAY': ':0', 02:22:46 INFO - 'HOME': '/home/cltbld', 02:22:46 INFO - 'LANG': 'en_US.UTF-8', 02:22:46 INFO - 'LANGUAGE': 'en_US:en', 02:22:46 INFO - 'LOGNAME': 'cltbld', 02:22:46 INFO - 'MAIL': '/var/mail/cltbld', 02:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:46 INFO - 'MOZ_NO_REMOTE': '1', 02:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:46 INFO - 'NO_EM_RESTART': '1', 02:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:46 INFO - 'PWD': '/builds/slave/test', 02:22:46 INFO - 'SHELL': '/bin/bash', 02:22:46 INFO - 'SHLVL': '1', 02:22:46 INFO - 'TERM': 'linux', 02:22:46 INFO - 'TMOUT': '86400', 02:22:46 INFO - 'USER': 'cltbld', 02:22:46 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:46 INFO - '_': '/tools/buildbot/bin/python'} 02:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 INFO - Cleaning up... 02:22:47 INFO - Return code: 0 02:22:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:22:47 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:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:47 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:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:47 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:47 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:22:47 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:22:47 INFO - Using env: {'DISPLAY': ':0', 02:22:47 INFO - 'HOME': '/home/cltbld', 02:22:47 INFO - 'LANG': 'en_US.UTF-8', 02:22:47 INFO - 'LANGUAGE': 'en_US:en', 02:22:47 INFO - 'LOGNAME': 'cltbld', 02:22:47 INFO - 'MAIL': '/var/mail/cltbld', 02:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:47 INFO - 'MOZ_NO_REMOTE': '1', 02:22:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:47 INFO - 'NO_EM_RESTART': '1', 02:22:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:47 INFO - 'PWD': '/builds/slave/test', 02:22:47 INFO - 'SHELL': '/bin/bash', 02:22:47 INFO - 'SHLVL': '1', 02:22:47 INFO - 'TERM': 'linux', 02:22:47 INFO - 'TMOUT': '86400', 02:22:47 INFO - 'USER': 'cltbld', 02:22:47 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:47 INFO - '_': '/tools/buildbot/bin/python'} 02:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:47 INFO - Downloading/unpacking jsonschema 02:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:47 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:22:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:22:49 INFO - Downloading/unpacking functools32 (from jsonschema) 02:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:22:49 INFO - warning: no files found matching '*.txt' 02:22:49 INFO - no previously-included directories found matching 'build' 02:22:49 INFO - no previously-included directories found matching 'dist' 02:22:49 INFO - no previously-included directories found matching '.git*' 02:22:49 INFO - Installing collected packages: jsonschema, functools32 02:22:49 INFO - Running setup.py install for functools32 02:22:49 INFO - warning: no files found matching '*.txt' 02:22:49 INFO - no previously-included directories found matching 'build' 02:22:49 INFO - no previously-included directories found matching 'dist' 02:22:49 INFO - no previously-included directories found matching '.git*' 02:22:49 INFO - Successfully installed jsonschema functools32 02:22:49 INFO - Cleaning up... 02:22:49 INFO - Return code: 0 02:22:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:22: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:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22: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:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:49 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 0x7f438e08cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fe5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', '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:22:49 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:22:49 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:22:49 INFO - Using env: {'DISPLAY': ':0', 02:22:49 INFO - 'HOME': '/home/cltbld', 02:22:49 INFO - 'LANG': 'en_US.UTF-8', 02:22:49 INFO - 'LANGUAGE': 'en_US:en', 02:22:49 INFO - 'LOGNAME': 'cltbld', 02:22:49 INFO - 'MAIL': '/var/mail/cltbld', 02:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:49 INFO - 'MOZ_NO_REMOTE': '1', 02:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:49 INFO - 'NO_EM_RESTART': '1', 02:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:49 INFO - 'PWD': '/builds/slave/test', 02:22:49 INFO - 'SHELL': '/bin/bash', 02:22:49 INFO - 'SHLVL': '1', 02:22:49 INFO - 'TERM': 'linux', 02:22:49 INFO - 'TMOUT': '86400', 02:22:49 INFO - 'USER': 'cltbld', 02:22:49 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:49 INFO - '_': '/tools/buildbot/bin/python'} 02:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:49 INFO - Downloading/unpacking flake8 02:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:22:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:22:51 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:22:51 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:22:51 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:22:51 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:22:51 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:22:51 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:22:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:22:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:22:52 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:22:52 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:22:52 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:22:52 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:22:52 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:22:52 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:22:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:22:52 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:22:52 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:22:52 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:22:52 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:22:52 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:22:52 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:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:22:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:52 INFO - no previously-included directories found matching 'docs/_build' 02:22:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:22:52 INFO - Running setup.py install for pep8 02:22:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:52 INFO - no previously-included directories found matching 'docs/_build' 02:22:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:22:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:22:52 INFO - Cleaning up... 02:22:52 INFO - Return code: 0 02:22:52 INFO - Running post-action listener: _resource_record_post_action 02:22:52 INFO - Running post-action listener: _start_resource_monitoring 02:22:52 INFO - Starting resource monitoring. 02:22:52 INFO - ##### 02:22:52 INFO - ##### Running install step. 02:22:52 INFO - ##### 02:22:52 INFO - Running pre-action listener: _resource_record_pre_action 02:22:52 INFO - Running main action method: install 02:22:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:53 INFO - Reading from file tmpfile_stdout 02:22:53 INFO - Detecting whether we're running mozinstall >=1.0... 02:22:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:22:53 INFO - Reading from file tmpfile_stdout 02:22:53 INFO - Output received: 02:22:53 INFO - Usage: mozinstall [options] installer 02:22:53 INFO - Options: 02:22:53 INFO - -h, --help show this help message and exit 02:22:53 INFO - -d DEST, --destination=DEST 02:22:53 INFO - Directory to install application into. [default: 02:22:53 INFO - "/builds/slave/test"] 02:22:53 INFO - --app=APP Application being installed. [default: firefox] 02:22:53 INFO - mkdir: /builds/slave/test/build/application 02:22:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:23:08 INFO - Reading from file tmpfile_stdout 02:23:08 INFO - Output received: 02:23:08 INFO - /builds/slave/test/build/application/firefox/firefox 02:23:08 INFO - Running post-action listener: _resource_record_post_action 02:23:08 INFO - ##### 02:23:08 INFO - ##### Running run-tests step. 02:23:08 INFO - ##### 02:23:08 INFO - Running pre-action listener: _resource_record_pre_action 02:23:08 INFO - Running main action method: run_tests 02:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:23:08 INFO - Python 2.7.3 02:23:08 INFO - Return code: 0 02:23:08 INFO - grabbing minidump binary from tooltool 02:23: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:23:08 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238d410>, '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 0x238e310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x238e7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:23:08 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:23:08 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:23:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:23:08 INFO - Return code: 0 02:23:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:23:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:23:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:23:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:23:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:23:09 INFO - Return code: 0 02:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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:23:09 INFO - Using env: {'DISPLAY': ':0', 02:23:09 INFO - 'HOME': '/home/cltbld', 02:23:09 INFO - 'LANG': 'en_US.UTF-8', 02:23:09 INFO - 'LANGUAGE': 'en_US:en', 02:23:09 INFO - 'LOGNAME': 'cltbld', 02:23:09 INFO - 'MAIL': '/var/mail/cltbld', 02:23:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:23:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:09 INFO - 'MOZ_NO_REMOTE': '1', 02:23:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:23:09 INFO - 'NO_EM_RESTART': '1', 02:23:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:09 INFO - 'PWD': '/builds/slave/test', 02:23:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:23:09 INFO - 'SHELL': '/bin/bash', 02:23:09 INFO - 'SHLVL': '1', 02:23:09 INFO - 'TERM': 'linux', 02:23:09 INFO - 'TMOUT': '86400', 02:23:09 INFO - 'USER': 'cltbld', 02:23:09 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307', 02:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:09 INFO - '_': '/tools/buildbot/bin/python'} 02:23:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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:23:10 INFO - mozversion application_buildid: 20160406014606 02:23:10 INFO - mozversion application_changeset: 67ce7164cbb233cd2852a5785fa384837f3f0788 02:23:10 INFO - mozversion application_display_name: Nightly 02:23:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:23:10 INFO - mozversion application_name: Firefox 02:23:10 INFO - mozversion application_remotingname: firefox 02:23:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:23:10 INFO - mozversion application_vendor: Mozilla 02:23:10 INFO - mozversion application_version: 48.0a1 02:23:10 INFO - mozversion platform_buildid: 20160406014606 02:23:10 INFO - mozversion platform_changeset: 67ce7164cbb233cd2852a5785fa384837f3f0788 02:23:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:23:10 INFO - mozversion platform_version: 48.0a1 02:23:10 INFO - using testdate: 1459934590 02:23:10 INFO - actual date: 1459934590 02:23:10 INFO - starting webserver on 'localhost:34307' 02:23:10 INFO - SUITE-START | Running 2 tests 02:23:10 INFO - TEST-START | dromaeo_css 02:23:10 INFO - operating with platform_type : linux_ 02:23:10 INFO - Initialising browser for dromaeo_css test... 02:23:10 INFO - TEST-INFO | started process 28894 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMsNtfC/profile http://localhost:34307/getInfo.html) 02:23:14 INFO - PROCESS | 28894 | __metrics Screen width/height:1600/1200 02:23:14 INFO - PROCESS | 28894 | colorDepth:24 02:23:14 INFO - PROCESS | 28894 | Browser inner width/height: 1024/697 02:23:14 INFO - PROCESS | 28894 | __metrics 02:23:15 ERROR - PROCESS | 28894 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:23:16 INFO - TEST-INFO | 28894: exit 0 02:23:16 INFO - Browser initialized. 02:23:16 INFO - Running cycle 1/1 for dromaeo_css test... 02:23:16 INFO - TEST-INFO | started process 28977 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMsNtfC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:28 INFO - PROCESS | 28977 | RSS: Main: 154198016 02:23:28 INFO - PROCESS | 28977 | 02:24:20 INFO - PROCESS | 28977 | 1459934660016 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:26:30 INFO - PROCESS | 28977 | Cycle 1(1): loaded http://localhost:34307/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34307/tests/dromaeo/cssquery-ext.html) 02:26:31 INFO - PROCESS | 28977 | RSS: Main: 270823424 02:26:31 INFO - PROCESS | 28977 | 02:29:34 INFO - PROCESS | 28977 | Cycle 1(1): loaded http://localhost:34307/tests/dromaeo/cssquery-ext.html (next: http://localhost:34307/tests/dromaeo/cssquery-jquery.html) 02:29:34 INFO - PROCESS | 28977 | RSS: Main: 238092288 02:29:34 INFO - PROCESS | 28977 | 02:32:36 INFO - PROCESS | 28977 | Cycle 1(1): loaded http://localhost:34307/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34307/tests/dromaeo/cssquery-mootools.html) 02:32:37 INFO - PROCESS | 28977 | RSS: Main: 231682048 02:32:37 INFO - PROCESS | 28977 | 02:35:39 INFO - PROCESS | 28977 | Cycle 1(1): loaded http://localhost:34307/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34307/tests/dromaeo/cssquery-prototype.html) 02:35:39 INFO - PROCESS | 28977 | RSS: Main: 219721728 02:35:39 INFO - PROCESS | 28977 | 02:38:42 INFO - PROCESS | 28977 | Cycle 1(1): loaded http://localhost:34307/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34307/tests/dromaeo/cssquery-yui.html) 02:38:43 INFO - PROCESS | 28977 | RSS: Main: 199221248 02:38:43 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | Cycle 1(1): loaded http://localhost:34307/tests/dromaeo/cssquery-yui.html (next: http://localhost:34307/tests/dromaeo/cssquery-dojo.html) 02:41:45 INFO - PROCESS | 28977 | RSS: Main: 271155200 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | __start_tp_report 02:41:45 INFO - PROCESS | 28977 | _x_x_mozilla_page_load 02:41:45 INFO - PROCESS | 28977 | _x_x_mozilla_page_load_details 02:41:45 INFO - PROCESS | 28977 | |i|pagename|runs| 02:41:45 INFO - PROCESS | 28977 | |0;dojo.html;2565.563854144795;2638.4854953284116;2644.338915271182;2656.402309480367;2666.906658266961;4470.575295346945;4553.544645535445;4585.380973568567;4588.793504292306;4593.793279302436;4570.908581828361;4591;4597.862064138081;4674.859754207379;4713.269443236295;4209.3054645983375;4218.957810421887;4223.324268117102;4262.722923009995;4268.530461649216;4282.550332215119;4316.84891028814;4337.934930976022;4365.454318210223;4366.257736184856;4122.814473348676;4212.073343864376;4216.177845320164;4272.786360681954;4308.332208507686;4212.178625168093;4218.894527636803;4221.957780422187;4223.619874211305;4227.87316380509;4201.6008479194425;4204.726692764961;4205.369194620801;4206.221848957953;4208.537060923296;4498.190325741365;4504.729716217037;4505.031418245093;4580.221362368372;4620.699654522446;4885.7312847793355;4886.340344053543;4888.584470320027;4895.73073480962;4945.406551213878;4915.336429582032;4921.901561968776;4922.360093187898;4936.5063493650705;4963.081829861487;4523.977380113111;4595.471520775121;4634.814607415666;4671.696339737906;4672.486026537078;4934.703917764874;4951.925721114186;4964.801407943642;4969.90060198794;4970.179920313108;4758;4771.880702982453;4780.11567859947;4786.377770889762;4833.758312084382;4867.683600565952;4931.7040977541465;4947.307376967227;4958.355413796184;4965;4849.466558678542;4877.5366340197625;4877.926831097537;4884.096442158212;4927.975360123248;4867.3915760529935;4868.756562171877;4869.464358920516;4877;4912.606991440676;4015.31739604268;4087.6729861611;4110.362893751473;4141.730787498803;4172.624463798273;3641.089727568108;3642.9817850911104;3648.434492653643;3665.6334366563665;3680.2087551176623;4223.092035212444;4241.533431322552;4242.427272318259;4277.486701595766;4287.721298115613;4858.65989380747;4878.804847806048;4923.187674033816;4930.309756634074;4959.454460009396;4222.746635201897;4252.043290259729;4257.340112282601;4261.829526818955;4288.313869780883;4336.739795612273;4439.733615983051;4452.844150454718;4471.61991230749;4497.77511124449;4594.448666160082;4628.652851036119;4634.189016922092;4671.182543054884;4678.50875658051;4913.729744864067;4917.975410123069;4920.6063514918005;4922.335484709662;4957.851264462072;4551.38556294896;4629.305604159403;4644.976775116238;4658.883527911694;4710.26990816424;4840.056189043103;4858.7570621469495;4860.416749990024;4861.076395484846;4907.950920490749;4910.8772280693265;4915.164422048332;4916.926246106383;4921.655484116078;4962.206047032458;4690.296455531561;4726.71639701619;4770.856874293777;4784.880377990578;4820.228763397839;4753.83361582343;4775.06886157196;4775.33145359643;4808.927866081942;4822.373091498223;4644.349791029326;4702.48272689997;4703.811847526061;4711.175544279668;4753.9762301188275;4676.906461870675;4723.645726570452;4797.5682188601795;4812.085703716282;4882.707037577757;4640.559146881041;4688.203005489143;4723.929141062818;4728.503507131839;4730.077634861169 02:41:45 INFO - PROCESS | 28977 | |1;ext.html;523.9738013099345;525.2698748739253;533.261432915412;540.5918531508711;549.9477549632784;26991.865040674795;34235.828820855895;34776.30447391051;34970.65029349712;35005;36549.634503654954;36941.630583694154;36982;37056;37065.25869482615;7685.231476852326;7865.370770338359;7872.252136047066;7873.645685944146;7873.881891771628;7737.226277372274;7850.764477065697;7864.134945156028;7866.803329916741;7994;7971.920280797205;8006.799830004267;8020.719274825382;8034.196580341948;8040.075391330013;7899.170587088359;7994.440389172761;7999.36005119589;8001.639926203335;8005.559694216816;7917.089534703525;8033.799155021113;8038.517688938682;8039.839203215962;8043.11525732169;36579;36838;37043.444348334524;37097.62902370969;37114.25771484582;3055.5416687497054;3211.2794192536935;3233.224026233687;3546.769459985093;3686;25646.230613081636;25683.61474577883;25709;25744;25876;3512.1570823002553;3552.129728216578;3630.473581330719;3656.542932133483;3743.232637309345;229408;230614;230796;231688;231760;53535.732321338146;53788;54196.45803541993;54203;54221.47560408561;57341.42658573444;57460.71269643625;57618;57671;57675.50218471572;14930.25348732559;15070.171140587154;15073.773893391608;15093.924530377279;15096.39614415433;11931.463084161234;11999.880001199876;12000.939995300143;12008.579699710468;12017.218880772896;18039.188236529386;18565.25738970453;18587.16357763904;18666.626667466575;18674.253029878924;6630.53586248958;6815.522913396015;6857.897131543031;6898.344657257552;6967.581945083311;862.9741107766777;902.4044130873723;920.2591913509555;947.8009617980301;989.6981420666766;25528.941418396545;25799.871000645253;25924.092656756922;26063.218103456926;26114.477710446066;794.7337641890032;937.9671711490065;976.84244170658;1041.9791604167874;1092.093562343253;11882.64352069422;12499.875001250053;12514.81227781584;12552.74894502123;12588.748225035448;204726;206518;208040;208066;208300;41526.79236603858;41595.37606935899;41644.583554164674;41659;41774.58225417768;1122.9045531129946;1128.1820680006929;1225.1791299829135;1227.2697744841983;1228.3305598448867;24335.20870599281;26203.475930481665;26392.472150556496;26524.734752653;26650.733492664825;37633;37919;37973.43039854349;37993;38018.42972355472;192613;193330;193432;193583;193869;29428;29449.852750736965;30321.848390757907;30350.69649303567;30542.694573054872;37621.247575048896;37868.24263514659;38192.80903595464;38241;38361.23277534489;32617.836910815295;32710.34579308353;32888.67111328952;33182.170445738084;33515.66484335223;32400.513992289663;32409.675903240666;32497;32633.836830815693;33257.66742332546;32759;33075.173120672174;33224.83387583047;33256.501152483215;33462.16344591405;28806.279843004093;28856.278593035342;28868.711312886604;28992.85503572553;29367.706322937353;22888.656670149627;23627.29118126459;23995.760042399354;24013.51972960566;24023.27930162098 02:41:45 INFO - PROCESS | 28977 | |2;jquery.html;350.5390411608733;351.978881267124;359.32267675431814;360.5079067073444;361.3153074923023;15535.766963495542;15852.524424267262;15867.127307998055;16003.199840008012;16230.675386492263;14912.40350385986;15102.244887755623;15104;15145.167015814126;15262.923685381558;4952.925706114411;5015.824446144376;5114.693118412907;5132.4610915853855;5138.8715282118055;3367.175042114673;5000.749962501878;5188.221766735001;5190.4809519047985;5199.948000520003;5160.277561141444;5167.638265321429;5172.40517340505;5173.017126745906;5183.688978661293;4969.055879382906;5112.514311140436;5142.588592912577;5149.897002059975;5156.175011998081;4925.802967881281;4940.629452791055;4942.036302920932;4947.233178857283;5020.623453241022;10764.515596798163;10789.298695584763;11959.162858599902;11982.340971246578;11986.820197697041;1865.3750993417223;1869.8971556564384;1936.3126090238045;1982.900854957261;2055.98972005139;8582.313414926854;8720.651173953034;8747.650093996295;8867.246284065863;9232.353735238536;1696.9915150424292;1762.8854124481995;1786.4015554789162;1808.1320965936293;1817.291256410001;57427.712861435844;60631.19603453749;61409.77180456411;61808;62094;9544.33189676716;9642.566084526214;9764.560594773253;9801.16690081351;9977.201823854075;13110.868891310965;13137.34313284332;13265.535706250235;13273.203607783564;13277;7184.461165412615;7476.102867655916;7568.924310756932;7616.619169041636;7650.923490765021;7374.9262507375315;7378.040854688856;7464.290892365217;7472.140703819022;7478.962605187048;4243.978780106079;4259.084296876186;4315.978420107942;4362.342309922209;4375.059362237134;1679.7396403557468;1686.1990554486522;1690.5689049292353;1729.3342063305465;1763.1536862306166;814.4909431605247;846.424431386663;858.6222062292696;902.5848109869403;911.9407238529475;14457.493987710379;14642.04826686262;15299.923500382427;15563.22183890801;15754.527364179094;793.4128744728974;807.249258189889;818.7543736878913;823.2343920154314;844.5692696724592;8772.342074344322;8839.497285461486;8874.156955089256;8927.508987005647;8976;374516;376520;377693;379628;397799;67094;69227.30772692208;70746.29253707397;71929.6403517979;73070.63464682642;948.2461443152711;985.2118305355687;993.1260490768078;1003.7139415266614;1012.9442880641489;14461.276936152939;14634.121952682874;14858.851411485746;15144.394224230908;15304.693906122038;32811;33445.33109337751;34006.3198736019;34288.485672715426;34738.131546711535;47179.52820471889;47351;47479.28781068217;47500.28749568828;47941.041179175525;6917.9308206917285;6972.755953541602;6977.581345119309;7055.223925368308;7186.640667966685;9531.666391676536;11335.263207891461;11618.128640351839;11647.883521165018;11702.531898724295;10138.138258248131;10171.389716617026;10191.439470829175;10368.75574931013;10510.159187264848;9914.801703965735;10116.342437741523;10132.544035518273;10235.84646230322;10268.948655256676;10308.948455257976;10348.741281468023;10492.003259690317;10544;10629.415382154055;7019.824504387431;7061.2232654409;7159.212486626571;7168.856622867618;7241.891371629536;7169.641517923978;9300.953495232481;10083.747906302107;10318.535665894902;10329.586816527255 02:41:45 INFO - PROCESS | 28977 | |3;mootools.html;1272.5800485839675;1360.8298962629672;1378.3315092180292;1499.940002399904;1729.5676080979763;6540.247871494779;6684.398404143626;6825.146856642919;6826.28324025977;6834.863302733942;7141.428685705144;7295.963520182391;7296.270372962701;7304.853902921938;7314.707411703525;1895.0430032833399;2127.181035301405;2144.238795227699;2175.260411460103;2179.8147157491553;2121.395402310342;2121.395402310342;2126.606577783115;2133.040131940625;2133.1680644548637;2044.3969029136367;2171.1315473810523;2184.7487538932987;2187.628103222448;2194.6049711052005;2107.4942013916702;2130.169233998734;2132.6374516332185;2145.066895900273;2149.2047942261306;2105.5578328551023;2121.7029615853794;2127.8936053197276;2128.0955593872695;2129.446343950584;7161.606111663908;7172.282771722838;7211.459140564479;7219.711211551532;7231.385332246766;4470.26240670289;4601.44782626087;4603.378543896564;4606.193916064675;4608.33179189019;2908.171171216193;2925.107842108156;2980.8360540170283;2981.7465515431213;3024.500957342036;908.2506931781306;909.6452383570413;915.8900931888217;929.7861491856843;937.9062093790635;10155.136813370873;10174.338667986558;10177.592896284139;10203.4388108654;10214.540345684461;4000.399940009022;4083.509978802503;4100.507939047334;4154.376843473503;4155.709100362946;8771.342149338698;8795.604197811113;8798.648054077765;8800.207981281588;8806.251468625238;3834.0989867381145;4527.909441811145;4532.138893610204;4568.88577785558;4572.7942242599165;3930.783806890591;4038.4749982502085;4060.6548443382053;4064.349704047338;4066.512018557792;3361.1933136047173;3428.6057103432927;3446.431338829065;3500.5274287970797;3529.94705079424;1132.6318946342308;1153.8211577205548;1154.468944285638;1168.3924359333269;1172.9237599556002;1190.6249531397755;1202.5851081376911;1208.5649166300125;1211.5335595795507;1226;6787.830304242433;6971.721131154798;7032.578045317195;7054.470914681419;7070.222275549685;432.07104724841844;439.5780051150867;444.62207123945035;445.2253079641401;445.5232900796117;5303.655262407931;5392.325959255093;5399.487048730364;5401.864953376197;5429.049916264717;8192.75421737349;8262.752117436487;8592.656293748305;8672.089430609822;9095.045020272775;8450.535220562928;8493.320534357237;8600.78498037554;8604.440711353742;8605.741827745178;6624.668766561653;6715.328467153343;6799.184097908283;6817.250102488722;6907.620080895699;6841.726330946706;6891.724331026703;6908.896366554405;6924.169099708067;6938.8612227756175;7700.961495192488;7764.22357764219;8086.6361013755695;8107.4324797263625;8113.391495637969;7680.500767450098;7966.203379661987;8095.919040809516;8096.068952070587;8237.382196335178;5830;5918.408159184047;6100.420460056288;6378.840528986812;6512.511561632802;6559.0817285581015;6576.8355791105605;6599.835004124743;6613;6619.205695316593;6001.699915004145;6196.628202307876;6197.349278325711;6324.272708638566;6553.901691474536;6085.4523092921845;6125;6138.877222455437;6202.131701561695;6247.5939063960695;6613.074169616161;6629.635370054693;6670.366315200048;6679.23188833271;6685.933140668726;6043.637381757108;6087.147799308012;6091.451769340781;6092.055731361646;6097.176881120992;5729.140628905698;5830.82507524775;5841.50347220474;5847.152162936265;5851.970740146272 02:41:45 INFO - PROCESS | 28977 | |4;prototype.html;301.38066273807317;304.847576211894;305.1455923414439;307.9722824945756;308.16948324266104;5232.816851410196;5306.655067420625;5329.573634109272;5342.946570534294;5366.114591092475;5225.268462415265;5254.658447200929;5283.207518872162;5303.575713942885;5309.362876454821;3847.057470919615;3977.7414468059483;4109.506859176903;4138.172365526899;4148.751074935499;4112.136451345206;4120.938185927213;4121.155163191554;4123.484564429446;4129.091599848044;4073.348264277716;4077.694172937027;4081.510218773751;4083.673306135502;4090.1206240658253;3817.10298079951;3883.436901649245;3891.610838916114;3945.4081887716784;4081.795910204493;3415.692587667097;3469.705075068622;3473.9578126561933;3476.704480119193;3483.599386070609;5065.873353166164;5067.87330316745;5075.543201111917;5078.720670363128;5082.898342033139;4627.722336659811;4628.44458664959;4632.166210082184;4636.585841318397;4638.489766125757;5564.33228012637;5564.638298510584;5566.721663916788;5574.721263936827;5575.721213939287;3825.1775868188197;3834.482344883433;3838.9424158637644;3840.8463661453507;3841.308564458396;5686.402927692574;5776.913346299809;5855.09246066856;5906.616069955439;5922.763089476416;3940.8817735467983;3946.506686664167;3949.881503554898;3966.147278335172;3968.464257325282;4011.4785077940264;4011.859584914514;4013.8795836124964;4016.8995775105855;4022.1754540319166;4621.768911554409;4622.884427889262;4627.768611569475;4630.143423466669;4639.721616703008;5587.161925711176;5589.161625756169;5589.944100558942;5593.0703907151965;5600.467955544217;1394.400407824645;1422.49501426994;1422.9501967431297;1423.0678905317034;1425.4369524037947;908.9590968406437;943.5848226780195;953.5851904421598;966.2751987701919;969.2827307792184;4710.128626204095;4774.212254977959;4864.878378040577;4881.51184881509;4905.656604037755;5821;5850.47345738877;5854.5901786875365;5855.092460668603;5861.17943487912;4919.655624106278;4933.605311575066;4938.58022068121;4940.431850337257;4942.481039490874;3074.1085085325453;3123.390938766964;3123.515855042472;3126.89055883043;3129.4992801151707;5495;5495.340559132929;5513.117901135799;5515.034868897927;5518.420565840609;5809.419058094241;5975.043992961106;5991.9700401498585;6007.189029480965;6008.279006519246;5483.561315094783;5510.752016159283;5513.050517973025;5513.917291240635;5515.503604675679;5834.562407819346;5843;5849.327327357409;5855.765769369348;5856.297244330707;5725.141228815711;5732.197492350991;5741.311042674905;5745.770169193185;5745.8563535911935;5501.4223506532835;5513.200585914939;5525.419830917856;5526.668399896019;5526.778928842801;3809;3940.1528671334654;3944.5069366329208;3981.8407263709128;4029.6776257899883;5633.014222510962;5697.914531282117;5723.542116630742;5746.195532625352;5778.56660750437;5562;5577.191307260506;5578.386377498391;5578.470045345685;5588.497035266845;5562.165675148598;5572.164175373727;5573.693446860299;5586.134149206717;5595.608307418442;5492.69790161529;5504.284443022381;5506.697131657797;5510.8071217507195;5512.173174023928;5400.540954018952;5614.5789065821045;5625.493705566519;5673.716314184357;5677.403872593318;5504.779808807602;5529.972350138223;5533.612647114664;5534.695591742493;5541.612087153861 02:41:45 INFO - PROCESS | 28977 | |5;yui.html;727.52210146548;748.5620911766616;773.2653978720217;777.3819813248467;785.0853755374989;12330.876691233085;12516.687082822935;12624.621261362163;12625;12642;11993;12014.399280035985;12051.216670916385;12141.57504487343;12190.695232619168;634.9460295874833;793.0364607002476;800.7237503061434;821.3880658909121;822.3010441125056;812.975610731679;813.5606772342958;815.9714409995652;816.2164322250646;817.1950628630794;855.6919508976763;861.0958493581745;861.6079683743912;861.8448679237734;863.0204717645455;669.4142625202948;840.3529282452508;850.8853402043343;851.638053827121;852.7484392104344;620.1442010026135;620.4756980351596;620.6090163197157;620.7330847735471;621.0125899819309;12130.514779408813;12188.51245950161;12192.695182620417;12261.877381226162;12288.631341059783;12223.877761222362;12459.439325230387;12661.620151395473;12715.55495557651;12755.107142499935;9864.852027219598;9935.751606209831;9948.651797187063;10177.643782467578;10228.232882533839;3298.175456135966;3305.289362787012;3336.7831090979007;3366.0575038989123;3409.6590340965954;44135.55864441347;44147;44438.33342499865;44470;44550.55449445529;10044.146247568893;10078.143357814668;10136.594536218468;10233.1301839343;10333;17897.37359192422;18073;18087.36694215696;18146;18214.72677909806;6687.197536295578;6780.69486873082;6787.660616969132;6893;7034.3669069782945;3746.325661380923;3749.9625003750157;3761.962380376216;3838.7504812187117;3892.2799282132896;2825.8304501729963;2877.798554101235;2878.553824157259;2888.162432894437;2889.4221155768932;510.6221396166884;513.4429144378316;516.5879366782349;524.8792777661083;527.9498447647468;564.6188822544766;593.7328202308978;606.6451126091198;614.6834380294152;624.4754406298731;7922.6416130981215;8190.058143313476;8202.958985205036;8403.831923361498;8493.405461617751;624.5378419969281;627.9466245369194;638.543441439373;646.599108552691;652.3769799841141;3657.213699054716;3694.131879008431;3759.285735710206;3841.654251117413;3850.4224366345275;47749.76125119352;48559.757201214474;48933;48992.265116023285;49016.26475602797;40569.59430405658;40571.7971410141;40898.59101408948;41191.7940410296;41199.794001029804;36073.63926360703;36119.458208126896;36330.818345908105;36493.27013459716;41047.58952410497;7435.330820226206;7457.142428620561;7485;7627.847443051219;7989.640466178919;31804.840975794974;32086.51870221902;32385.35229295448;33218.5017224737;33259.33481330312;37465;38048.239035219696;39159;41158;41214.58785412108;10120.63371444875;10151.340162889388;10253.74365640865;10266;10524.684259472227;9930.304878658713;10985.395803230898;11192.944035279772;11336.886631133582;11728.354940478355;10417.16662667;10962.177836662122;11097.500612472619;11130.387828669502;11153.051990580567;10544.156467482737;10564.471776411303;10696.572137114428;11083.72290692739;11320.433978300887;10750;10839.67480975572;11050.502727377449;11081.722956926142;11091.722706932069;9089.727308180765;9437.858432123661;9599.664011759554;9622.855657165008;9897.752556186153;9625.374350667184;9718.854217186888;9860.802783944424;9944.105030547285;10049.799004020026 02:41:45 INFO - PROCESS | 28977 | __end_tp_report 02:41:45 INFO - PROCESS | 28977 | __start_cc_report 02:41:45 INFO - PROCESS | 28977 | _x_x_mozilla_cycle_collect,1077 02:41:45 INFO - PROCESS | 28977 | __end_cc_report 02:41:45 INFO - PROCESS | 28977 | __startTimestamp1459935705875__endTimestamp 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | ------- Summary: start ------- 02:41:45 INFO - PROCESS | 28977 | Number of tests: 6 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | [#0] dojo.html Cycles:165 Average:4540.28 Median:4672.49 stddev:460.89 (9.9%) stddev-sans-first:435.49 02:41:45 INFO - PROCESS | 28977 | Values: 2565.6 2638.5 2644.3 2656.4 2666.9 4470.6 4553.5 4585.4 4588.8 4593.8 4570.9 4591.0 4597.9 4674.9 4713.3 4209.3 4219.0 4223.3 4262.7 4268.5 4282.6 4316.8 4337.9 4365.5 4366.3 4122.8 4212.1 4216.2 4272.8 4308.3 4212.2 4218.9 4222.0 4223.6 4227.9 4201.6 4204.7 4205.4 4206.2 4208.5 4498.2 4504.7 4505.0 4580.2 4620.7 4885.7 4886.3 4888.6 4895.7 4945.4 4915.3 4921.9 4922.4 4936.5 4963.1 4524.0 4595.5 4634.8 4671.7 4672.5 4934.7 4951.9 4964.8 4969.9 4970.2 4758.0 4771.9 4780.1 4786.4 4833.8 4867.7 4931.7 4947.3 4958.4 4965.0 4849.5 4877.5 4877.9 4884.1 4928.0 4867.4 4868.8 4869.5 4877.0 4912.6 4015.3 4087.7 4110.4 4141.7 4172.6 3641.1 3643.0 3648.4 3665.6 3680.2 4223.1 4241.5 4242.4 4277.5 4287.7 4858.7 4878.8 4923.2 4930.3 4959.5 4222.7 4252.0 4257.3 4261.8 4288.3 4336.7 4439.7 4452.8 4471.6 4497.8 4594.4 4628.7 4634.2 4671.2 4678.5 4913.7 4918.0 4920.6 4922.3 4957.9 4551.4 4629.3 4645.0 4658.9 4710.3 4840.1 4858.8 4860.4 4861.1 4908.0 4910.9 4915.2 4916.9 4921.7 4962.2 4690.3 4726.7 4770.9 4784.9 4820.2 4753.8 4775.1 4775.3 4808.9 4822.4 4644.3 4702.5 4703.8 4711.2 4754.0 4676.9 4723.6 4797.6 4812.1 4882.7 4640.6 4688.2 4723.9 4728.5 4730.1 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | [#1] ext.html Cycles:180 Average:37270.75 Median:33875.75 stddev:54693.10 (161.5%) stddev-sans-first:54776.93 02:41:45 INFO - PROCESS | 28977 | Values: 524.0 525.3 533.3 540.6 549.9 26991.9 34235.8 34776.3 34970.7 35005.0 36549.6 36941.6 36982.0 37056.0 37065.3 7685.2 7865.4 7872.3 7873.6 7873.9 7737.2 7850.8 7864.1 7866.8 7994.0 7971.9 8006.8 8020.7 8034.2 8040.1 7899.2 7994.4 7999.4 8001.6 8005.6 7917.1 8033.8 8038.5 8039.8 8043.1 36579.0 36838.0 37043.4 37097.6 37114.3 3055.5 3211.3 3233.2 3546.8 3686.0 25646.2 25683.6 25709.0 25744.0 25876.0 3512.2 3552.1 3630.5 3656.5 3743.2 229408.0 230614.0 230796.0 231688.0 231760.0 53535.7 53788.0 54196.5 54203.0 54221.5 57341.4 57460.7 57618.0 57671.0 57675.5 14930.3 15070.2 15073.8 15093.9 15096.4 11931.5 11999.9 12000.9 12008.6 12017.2 18039.2 18565.3 18587.2 18666.6 18674.3 6630.5 6815.5 6857.9 6898.3 6967.6 863.0 902.4 920.3 947.8 989.7 25528.9 25799.9 25924.1 26063.2 26114.5 794.7 938.0 976.8 1042.0 1092.1 11882.6 12499.9 12514.8 12552.7 12588.7 204726.0 206518.0 208040.0 208066.0 208300.0 41526.8 41595.4 41644.6 41659.0 41774.6 1122.9 1128.2 1225.2 1227.3 1228.3 24335.2 26203.5 26392.5 26524.7 26650.7 37633.0 37919.0 37973.4 37993.0 38018.4 192613.0 193330.0 193432.0 193583.0 193869.0 29428.0 29449.9 30321.8 30350.7 30542.7 37621.2 37868.2 38192.8 38241.0 38361.2 32617.8 32710.3 32888.7 33182.2 33515.7 32400.5 32409.7 32497.0 32633.8 33257.7 32759.0 33075.2 33224.8 33256.5 33462.2 28806.3 28856.3 28868.7 28992.9 29367.7 22888.7 23627.3 23995.8 24013.5 24023.3 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | [#2] jquery.html Cycles:180 Average:22924.58 Median:47415.14 stddev:62742.76 (132.3%) stddev-sans-first:62895.87 02:41:45 INFO - PROCESS | 28977 | Values: 350.5 352.0 359.3 360.5 361.3 15535.8 15852.5 15867.1 16003.2 16230.7 14912.4 15102.2 15104.0 15145.2 15262.9 4952.9 5015.8 5114.7 5132.5 5138.9 3367.2 5000.7 5188.2 5190.5 5199.9 5160.3 5167.6 5172.4 5173.0 5183.7 4969.1 5112.5 5142.6 5149.9 5156.2 4925.8 4940.6 4942.0 4947.2 5020.6 10764.5 10789.3 11959.2 11982.3 11986.8 1865.4 1869.9 1936.3 1982.9 2056.0 8582.3 8720.7 8747.7 8867.2 9232.4 1697.0 1762.9 1786.4 1808.1 1817.3 57427.7 60631.2 61409.8 61808.0 62094.0 9544.3 9642.6 9764.6 9801.2 9977.2 13110.9 13137.3 13265.5 13273.2 13277.0 7184.5 7476.1 7568.9 7616.6 7650.9 7374.9 7378.0 7464.3 7472.1 7479.0 4244.0 4259.1 4316.0 4362.3 4375.1 1679.7 1686.2 1690.6 1729.3 1763.2 814.5 846.4 858.6 902.6 911.9 14457.5 14642.0 15299.9 15563.2 15754.5 793.4 807.2 818.8 823.2 844.6 8772.3 8839.5 8874.2 8927.5 8976.0 374516.0 376520.0 377693.0 379628.0 397799.0 67094.0 69227.3 70746.3 71929.6 73070.6 948.2 985.2 993.1 1003.7 1012.9 14461.3 14634.1 14858.9 15144.4 15304.7 32811.0 33445.3 34006.3 34288.5 34738.1 47179.5 47351.0 47479.3 47500.3 47941.0 6917.9 6972.8 6977.6 7055.2 7186.6 9531.7 11335.3 11618.1 11647.9 11702.5 10138.1 10171.4 10191.4 10368.8 10510.2 9914.8 10116.3 10132.5 10235.8 10268.9 10308.9 10348.7 10492.0 10544.0 10629.4 7019.8 7061.2 7159.2 7168.9 7241.9 7169.6 9301.0 10083.7 10318.5 10329.6 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | [#3] mootools.html Cycles:180 Average:5066.20 Median:5885.19 stddev:2638.22 (44.8%) stddev-sans-first:2630.21 02:41:45 INFO - PROCESS | 28977 | Values: 1272.6 1360.8 1378.3 1499.9 1729.6 6540.2 6684.4 6825.1 6826.3 6834.9 7141.4 7296.0 7296.3 7304.9 7314.7 1895.0 2127.2 2144.2 2175.3 2179.8 2121.4 2121.4 2126.6 2133.0 2133.2 2044.4 2171.1 2184.7 2187.6 2194.6 2107.5 2130.2 2132.6 2145.1 2149.2 2105.6 2121.7 2127.9 2128.1 2129.4 7161.6 7172.3 7211.5 7219.7 7231.4 4470.3 4601.4 4603.4 4606.2 4608.3 2908.2 2925.1 2980.8 2981.7 3024.5 908.3 909.6 915.9 929.8 937.9 10155.1 10174.3 10177.6 10203.4 10214.5 4000.4 4083.5 4100.5 4154.4 4155.7 8771.3 8795.6 8798.6 8800.2 8806.3 3834.1 4527.9 4532.1 4568.9 4572.8 3930.8 4038.5 4060.7 4064.3 4066.5 3361.2 3428.6 3446.4 3500.5 3529.9 1132.6 1153.8 1154.5 1168.4 1172.9 1190.6 1202.6 1208.6 1211.5 1226.0 6787.8 6971.7 7032.6 7054.5 7070.2 432.1 439.6 444.6 445.2 445.5 5303.7 5392.3 5399.5 5401.9 5429.0 8192.8 8262.8 8592.7 8672.1 9095.0 8450.5 8493.3 8600.8 8604.4 8605.7 6624.7 6715.3 6799.2 6817.3 6907.6 6841.7 6891.7 6908.9 6924.2 6938.9 7701.0 7764.2 8086.6 8107.4 8113.4 7680.5 7966.2 8095.9 8096.1 8237.4 5830.0 5918.4 6100.4 6378.8 6512.5 6559.1 6576.8 6599.8 6613.0 6619.2 6001.7 6196.6 6197.3 6324.3 6553.9 6085.5 6125.0 6138.9 6202.1 6247.6 6613.1 6629.6 6670.4 6679.2 6685.9 6043.6 6087.1 6091.5 6092.1 6097.2 5729.1 5830.8 5841.5 5847.2 5852.0 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | [#4] prototype.html Cycles:180 Average:4618.81 Median:5308.01 stddev:1376.86 (25.9%) stddev-sans-first:1342.04 02:41:45 INFO - PROCESS | 28977 | Values: 301.4 304.8 305.1 308.0 308.2 5232.8 5306.7 5329.6 5342.9 5366.1 5225.3 5254.7 5283.2 5303.6 5309.4 3847.1 3977.7 4109.5 4138.2 4148.8 4112.1 4120.9 4121.2 4123.5 4129.1 4073.3 4077.7 4081.5 4083.7 4090.1 3817.1 3883.4 3891.6 3945.4 4081.8 3415.7 3469.7 3474.0 3476.7 3483.6 5065.9 5067.9 5075.5 5078.7 5082.9 4627.7 4628.4 4632.2 4636.6 4638.5 5564.3 5564.6 5566.7 5574.7 5575.7 3825.2 3834.5 3838.9 3840.8 3841.3 5686.4 5776.9 5855.1 5906.6 5922.8 3940.9 3946.5 3949.9 3966.1 3968.5 4011.5 4011.9 4013.9 4016.9 4022.2 4621.8 4622.9 4627.8 4630.1 4639.7 5587.2 5589.2 5589.9 5593.1 5600.5 1394.4 1422.5 1423.0 1423.1 1425.4 909.0 943.6 953.6 966.3 969.3 4710.1 4774.2 4864.9 4881.5 4905.7 5821.0 5850.5 5854.6 5855.1 5861.2 4919.7 4933.6 4938.6 4940.4 4942.5 3074.1 3123.4 3123.5 3126.9 3129.5 5495.0 5495.3 5513.1 5515.0 5518.4 5809.4 5975.0 5992.0 6007.2 6008.3 5483.6 5510.8 5513.1 5513.9 5515.5 5834.6 5843.0 5849.3 5855.8 5856.3 5725.1 5732.2 5741.3 5745.8 5745.9 5501.4 5513.2 5525.4 5526.7 5526.8 3809.0 3940.2 3944.5 3981.8 4029.7 5633.0 5697.9 5723.5 5746.2 5778.6 5562.0 5577.2 5578.4 5578.5 5588.5 5562.2 5572.2 5573.7 5586.1 5595.6 5492.7 5504.3 5506.7 5510.8 5512.2 5400.5 5614.6 5625.5 5673.7 5677.4 5504.8 5530.0 5533.6 5534.7 5541.6 02:41:45 INFO - PROCESS | 28977 | 02:41:45 INFO - PROCESS | 28977 | [#5] yui.html Cycles:180 Average:12417.18 Median:40735.19 stddev:13576.27 (33.3%) stddev-sans-first:13585.97 02:41:45 INFO - PROCESS | 28977 | Values: 727.5 748.6 773.3 777.4 785.1 12330.9 12516.7 12624.6 12625.0 12642.0 11993.0 12014.4 12051.2 12141.6 12190.7 634.9 793.0 800.7 821.4 822.3 813.0 813.6 816.0 816.2 817.2 855.7 861.1 861.6 861.8 863.0 669.4 840.4 850.9 851.6 852.7 620.1 620.5 620.6 620.7 621.0 12130.5 12188.5 12192.7 12261.9 12288.6 12223.9 12459.4 12661.6 12715.6 12755.1 9864.9 9935.8 9948.7 10177.6 10228.2 3298.2 3305.3 3336.8 3366.1 3409.7 44135.6 44147.0 44438.3 44470.0 44550.6 10044.1 10078.1 10136.6 10233.1 10333.0 17897.4 18073.0 18087.4 18146.0 18214.7 6687.2 6780.7 6787.7 6893.0 7034.4 3746.3 3750.0 3762.0 3838.8 3892.3 2825.8 2877.8 2878.6 2888.2 2889.4 510.6 513.4 516.6 524.9 527.9 564.6 593.7 606.6 614.7 624.5 7922.6 8190.1 8203.0 8403.8 8493.4 624.5 627.9 638.5 646.6 652.4 3657.2 3694.1 3759.3 3841.7 3850.4 47749.8 48559.8 48933.0 48992.3 49016.3 40569.6 40571.8 40898.6 41191.8 41199.8 36073.6 36119.5 36330.8 36493.3 41047.6 7435.3 7457.1 7485.0 7627.8 7989.6 31804.8 32086.5 32385.4 33218.5 33259.3 37465.0 38048.2 39159.0 41158.0 41214.6 10120.6 10151.3 10253.7 10266.0 10524.7 9930.3 10985.4 11192.9 11336.9 11728.4 10417.2 10962.2 11097.5 11130.4 11153.1 10544.2 10564.5 10696.6 11083.7 11320.4 10750.0 10839.7 11050.5 11081.7 11091.7 9089.7 9437.9 9599.7 9622.9 9897.8 9625.4 9718.9 9860.8 9944.1 10049.8 02:41:45 INFO - PROCESS | 28977 | -------- Summary: end -------- 02:41:45 INFO - PROCESS | 28977 | 02:41:46 INFO - TEST-INFO | 28977: exit 0 02:41:46 INFO - TEST-OK | dromaeo_css | took 1116546ms 02:41:46 INFO - TEST-START | kraken 02:41:46 INFO - operating with platform_type : linux_ 02:41:46 INFO - Initialising browser for kraken test... 02:41:46 INFO - TEST-INFO | started process 29152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC0CgjW/profile http://localhost:34307/getInfo.html) 02:41:51 INFO - PROCESS | 29152 | __metrics Screen width/height:1600/1200 02:41:51 INFO - PROCESS | 29152 | colorDepth:24 02:41:51 INFO - PROCESS | 29152 | Browser inner width/height: 1024/697 02:41:51 INFO - PROCESS | 29152 | __metrics 02:41:52 ERROR - PROCESS | 29152 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:41:52 INFO - TEST-INFO | 29152: exit 0 02:41:52 INFO - Browser initialized. 02:41:52 INFO - Running cycle 1/1 for kraken test... 02:41:52 INFO - TEST-INFO | started process 29235 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC0CgjW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:04 INFO - PROCESS | 29235 | RSS: Main: 154439680 02:42:04 INFO - PROCESS | 29235 | 02:42:07 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34307/tests/kraken/driver.html?testName=audio-beat-detection) 02:42:08 INFO - PROCESS | 29235 | RSS: Main: 195256320 02:42:08 INFO - PROCESS | 29235 | 02:42:10 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34307/tests/kraken/driver.html?testName=audio-dft) 02:42:11 INFO - PROCESS | 29235 | RSS: Main: 185540608 02:42:11 INFO - PROCESS | 29235 | 02:42:14 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34307/tests/kraken/driver.html?testName=audio-fft) 02:42:15 INFO - PROCESS | 29235 | RSS: Main: 169893888 02:42:15 INFO - PROCESS | 29235 | 02:42:17 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34307/tests/kraken/driver.html?testName=audio-oscillator) 02:42:17 INFO - PROCESS | 29235 | RSS: Main: 172584960 02:42:17 INFO - PROCESS | 29235 | 02:42:20 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34307/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:42:20 INFO - PROCESS | 29235 | RSS: Main: 173543424 02:42:20 INFO - PROCESS | 29235 | 02:42:23 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34307/tests/kraken/driver.html?testName=imaging-darkroom) 02:42:24 INFO - PROCESS | 29235 | RSS: Main: 174088192 02:42:24 INFO - PROCESS | 29235 | 02:42:27 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34307/tests/kraken/driver.html?testName=imaging-desaturate) 02:42:28 INFO - PROCESS | 29235 | RSS: Main: 174923776 02:42:28 INFO - PROCESS | 29235 | 02:42:31 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34307/tests/kraken/driver.html?testName=json-parse-financial) 02:42:31 INFO - PROCESS | 29235 | RSS: Main: 174661632 02:42:31 INFO - PROCESS | 29235 | 02:42:33 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34307/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:42:34 INFO - PROCESS | 29235 | RSS: Main: 183242752 02:42:34 INFO - PROCESS | 29235 | 02:42:35 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:42:36 INFO - PROCESS | 29235 | RSS: Main: 181510144 02:42:36 INFO - PROCESS | 29235 | 02:42:38 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:42:39 INFO - PROCESS | 29235 | RSS: Main: 194359296 02:42:39 INFO - PROCESS | 29235 | 02:42:41 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:42:42 INFO - PROCESS | 29235 | RSS: Main: 198029312 02:42:42 INFO - PROCESS | 29235 | 02:42:44 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:42:45 INFO - PROCESS | 29235 | RSS: Main: 198463488 02:42:45 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | Cycle 1(1): loaded http://localhost:34307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34307/tests/kraken/driver.html?testName=ai-astar) 02:42:47 INFO - PROCESS | 29235 | RSS: Main: 260472832 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | __start_tp_report 02:42:47 INFO - PROCESS | 29235 | _x_x_mozilla_page_load 02:42:47 INFO - PROCESS | 29235 | _x_x_mozilla_page_load_details 02:42:47 INFO - PROCESS | 29235 | |i|pagename|runs| 02:42:47 INFO - PROCESS | 29235 | |0;ai-astar;113;114;107;112;114;114;100;112;111;113 02:42:47 INFO - PROCESS | 29235 | |1;audio-beat-detection;158;130;159;176;132;161;153;132;171;132 02:42:47 INFO - PROCESS | 29235 | |2;audio-dft;161;160;162;159;159;161;161;162;163;162 02:42:47 INFO - PROCESS | 29235 | |3;audio-fft;72;74;99;100;74;74;74;110;106;78 02:42:47 INFO - PROCESS | 29235 | |4;audio-oscillator;95;95;144;95;94;93;145;94;94;136 02:42:47 INFO - PROCESS | 29235 | |5;imaging-gaussian-blur;116;117;116;117;117;117;117;117;118;117 02:42:47 INFO - PROCESS | 29235 | |6;imaging-darkroom;146;146;145;146;146;146;147;145;145;146 02:42:47 INFO - PROCESS | 29235 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;96;95 02:42:47 INFO - PROCESS | 29235 | |8;json-parse-financial;65;67;64;64;63;64;63;74;63;62 02:42:47 INFO - PROCESS | 29235 | |9;json-stringify-tinderbox;64;63;63;64;65;64;64;64;64;65 02:42:47 INFO - PROCESS | 29235 | |10;stanford-crypto-aes;89;81;82;84;89;81;88;81;83;82 02:42:47 INFO - PROCESS | 29235 | |11;stanford-crypto-ccm;117;125;126;123;117;118;131;124;121;118 02:42:47 INFO - PROCESS | 29235 | |12;stanford-crypto-pbkdf2;138;142;138;139;138;127;139;137;139;140 02:42:47 INFO - PROCESS | 29235 | |13;stanford-crypto-sha256-iterative;66;67;68;70;68;67;68;67;69;67 02:42:47 INFO - PROCESS | 29235 | __end_tp_report 02:42:47 INFO - PROCESS | 29235 | __start_cc_report 02:42:47 INFO - PROCESS | 29235 | _x_x_mozilla_cycle_collect,1192 02:42:47 INFO - PROCESS | 29235 | __end_cc_report 02:42:47 INFO - PROCESS | 29235 | __startTimestamp1459935767600__endTimestamp 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | ------- Summary: start ------- 02:42:47 INFO - PROCESS | 29235 | Number of tests: 14 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#0] ai-astar Cycles:10 Average:111.00 Median:113.00 stddev:4.40 (3.9%) stddev-sans-first:4.60 02:42:47 INFO - PROCESS | 29235 | Values: 113.0 114.0 107.0 112.0 114.0 114.0 100.0 112.0 111.0 113.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#1] audio-beat-detection Cycles:10 Average:150.40 Median:158.50 stddev:17.52 (11.1%) stddev-sans-first:18.37 02:42:47 INFO - PROCESS | 29235 | Values: 158.0 130.0 159.0 176.0 132.0 161.0 153.0 132.0 171.0 132.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.33 (0.8%) stddev-sans-first:1.41 02:42:47 INFO - PROCESS | 29235 | Values: 161.0 160.0 162.0 159.0 159.0 161.0 161.0 162.0 163.0 162.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#3] audio-fft Cycles:10 Average:86.10 Median:74.00 stddev:15.55 (21.0%) stddev-sans-first:15.64 02:42:47 INFO - PROCESS | 29235 | Values: 72.0 74.0 99.0 100.0 74.0 74.0 74.0 110.0 106.0 78.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#4] audio-oscillator Cycles:10 Average:108.50 Median:94.00 stddev:23.01 (24.5%) stddev-sans-first:23.89 02:42:47 INFO - PROCESS | 29235 | Values: 95.0 95.0 144.0 95.0 94.0 93.0 145.0 94.0 94.0 136.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 02:42:47 INFO - PROCESS | 29235 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 02:42:47 INFO - PROCESS | 29235 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 147.0 145.0 145.0 146.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:42:47 INFO - PROCESS | 29235 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.00 stddev:3.48 (5.4%) stddev-sans-first:3.69 02:42:47 INFO - PROCESS | 29235 | Values: 65.0 67.0 64.0 64.0 63.0 64.0 63.0 74.0 63.0 62.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 02:42:47 INFO - PROCESS | 29235 | Values: 64.0 63.0 63.0 64.0 65.0 64.0 64.0 64.0 64.0 65.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.50 stddev:3.37 (4.0%) stddev-sans-first:3.05 02:42:47 INFO - PROCESS | 29235 | Values: 89.0 81.0 82.0 84.0 89.0 81.0 88.0 81.0 83.0 82.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:123.50 stddev:4.64 (3.8%) stddev-sans-first:4.56 02:42:47 INFO - PROCESS | 29235 | Values: 117.0 125.0 126.0 123.0 117.0 118.0 131.0 124.0 121.0 118.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.70 Median:139.00 stddev:4.00 (2.9%) stddev-sans-first:4.24 02:42:47 INFO - PROCESS | 29235 | Values: 138.0 142.0 138.0 139.0 138.0 127.0 139.0 137.0 139.0 140.0 02:42:47 INFO - PROCESS | 29235 | 02:42:47 INFO - PROCESS | 29235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.05 02:42:47 INFO - PROCESS | 29235 | Values: 66.0 67.0 68.0 70.0 68.0 67.0 68.0 67.0 69.0 67.0 02:42:47 INFO - PROCESS | 29235 | -------- Summary: end -------- 02:42:47 INFO - PROCESS | 29235 | 02:42:48 INFO - TEST-INFO | 29235: exit 0 02:42:48 INFO - TEST-OK | kraken | took 61905ms 02:42:48 INFO - SUITE-END | took 1178s 02:42:48 INFO - Completed test suite (00:19:38) 02:42:48 INFO - javascript benchmark 02:42:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2565.563854144795, 2638.4854953284116, 2644.338915271182, 2656.402309480367, 2666.906658266961, 4470.575295346945, 4553.544645535445, 4585.380973568567, 4588.793504292306, 4593.793279302436, 4570.908581828361, 4591.0, 4597.862064138081, 4674.859754207379, 4713.269443236295, 4209.3054645983375, 4218.957810421887, 4223.324268117102, 4262.722923009995, 4268.530461649216, 4282.550332215119, 4316.84891028814, 4337.934930976022, 4365.454318210223, 4366.257736184856, 4122.814473348676, 4212.073343864376, 4216.177845320164, 4272.786360681954, 4308.332208507686, 4212.178625168093, 4218.894527636803, 4221.957780422187, 4223.619874211305, 4227.87316380509, 4201.6008479194425, 4204.726692764961, 4205.369194620801, 4206.221848957953, 4208.537060923296, 4498.190325741365, 4504.729716217037, 4505.031418245093, 4580.221362368372, 4620.699654522446, 4885.7312847793355, 4886.340344053543, 4888.584470320027, 4895.73073480962, 4945.406551213878, 4915.336429582032, 4921.901561968776, 4922.360093187898, 4936.5063493650705, 4963.081829861487, 4523.977380113111, 4595.471520775121, 4634.814607415666, 4671.696339737906, 4672.486026537078, 4934.703917764874, 4951.925721114186, 4964.801407943642, 4969.90060198794, 4970.179920313108, 4758.0, 4771.880702982453, 4780.11567859947, 4786.377770889762, 4833.758312084382, 4867.683600565952, 4931.7040977541465, 4947.307376967227, 4958.355413796184, 4965.0, 4849.466558678542, 4877.5366340197625, 4877.926831097537, 4884.096442158212, 4927.975360123248, 4867.3915760529935, 4868.756562171877, 4869.464358920516, 4877.0, 4912.606991440676, 4015.31739604268, 4087.6729861611, 4110.362893751473, 4141.730787498803, 4172.624463798273, 3641.089727568108, 3642.9817850911104, 3648.434492653643, 3665.6334366563665, 3680.2087551176623, 4223.092035212444, 4241.533431322552, 4242.427272318259, 4277.486701595766, 4287.721298115613, 4858.65989380747, 4878.804847806048, 4923.187674033816, 4930.309756634074, 4959.454460009396, 4222.746635201897, 4252.043290259729, 4257.340112282601, 4261.829526818955, 4288.313869780883, 4336.739795612273, 4439.733615983051, 4452.844150454718, 4471.61991230749, 4497.77511124449, 4594.448666160082, 4628.652851036119, 4634.189016922092, 4671.182543054884, 4678.50875658051, 4913.729744864067, 4917.975410123069, 4920.6063514918005, 4922.335484709662, 4957.851264462072, 4551.38556294896, 4629.305604159403, 4644.976775116238, 4658.883527911694, 4710.26990816424, 4840.056189043103, 4858.7570621469495, 4860.416749990024, 4861.076395484846, 4907.950920490749, 4910.8772280693265, 4915.164422048332, 4916.926246106383, 4921.655484116078, 4962.206047032458, 4690.296455531561, 4726.71639701619, 4770.856874293777, 4784.880377990578, 4820.228763397839, 4753.83361582343, 4775.06886157196, 4775.33145359643, 4808.927866081942, 4822.373091498223, 4644.349791029326, 4702.48272689997, 4703.811847526061, 4711.175544279668, 4753.9762301188275, 4676.906461870675, 4723.645726570452, 4797.5682188601795, 4812.085703716282, 4882.707037577757, 4640.559146881041, 4688.203005489143, 4723.929141062818, 4728.503507131839, 4730.077634861169], "unit": "score", "name": "dojo.html", "value": 4511.994613578226}, {"lowerIsBetter": false, "replicates": [523.9738013099345, 525.2698748739253, 533.261432915412, 540.5918531508711, 549.9477549632784, 26991.865040674795, 34235.828820855895, 34776.30447391051, 34970.65029349712, 35005.0, 36549.634503654954, 36941.630583694154, 36982.0, 37056.0, 37065.25869482615, 7685.231476852326, 7865.370770338359, 7872.252136047066, 7873.645685944146, 7873.881891771628, 7737.226277372274, 7850.764477065697, 7864.134945156028, 7866.803329916741, 7994.0, 7971.920280797205, 8006.799830004267, 8020.719274825382, 8034.196580341948, 8040.075391330013, 7899.170587088359, 7994.440389172761, 7999.36005119589, 8001.639926203335, 8005.559694216816, 7917.089534703525, 8033.799155021113, 8038.517688938682, 8039.839203215962, 8043.11525732169, 36579.0, 36838.0, 37043.444348334524, 37097.62902370969, 37114.25771484582, 3055.5416687497054, 3211.2794192536935, 3233.224026233687, 3546.769459985093, 3686.0, 25646.230613081636, 25683.61474577883, 25709.0, 25744.0, 25876.0, 3512.1570823002553, 3552.129728216578, 3630.473581330719, 3656.542932133483, 3743.232637309345, 229408.0, 230614.0, 230796.0, 231688.0, 231760.0, 53535.732321338146, 53788.0, 54196.45803541993, 54203.0, 54221.47560408561, 57341.42658573444, 57460.71269643625, 57618.0, 57671.0, 57675.50218471572, 14930.25348732559, 15070.171140587154, 15073.773893391608, 15093.924530377279, 15096.39614415433, 11931.463084161234, 11999.880001199876, 12000.939995300143, 12008.579699710468, 12017.218880772896, 18039.188236529386, 18565.25738970453, 18587.16357763904, 18666.626667466575, 18674.253029878924, 6630.53586248958, 6815.522913396015, 6857.897131543031, 6898.344657257552, 6967.581945083311, 862.9741107766777, 902.4044130873723, 920.2591913509555, 947.8009617980301, 989.6981420666766, 25528.941418396545, 25799.871000645253, 25924.092656756922, 26063.218103456926, 26114.477710446066, 794.7337641890032, 937.9671711490065, 976.84244170658, 1041.9791604167874, 1092.093562343253, 11882.64352069422, 12499.875001250053, 12514.81227781584, 12552.74894502123, 12588.748225035448, 204726.0, 206518.0, 208040.0, 208066.0, 208300.0, 41526.79236603858, 41595.37606935899, 41644.583554164674, 41659.0, 41774.58225417768, 1122.9045531129946, 1128.1820680006929, 1225.1791299829135, 1227.2697744841983, 1228.3305598448867, 24335.20870599281, 26203.475930481665, 26392.472150556496, 26524.734752653, 26650.733492664825, 37633.0, 37919.0, 37973.43039854349, 37993.0, 38018.42972355472, 192613.0, 193330.0, 193432.0, 193583.0, 193869.0, 29428.0, 29449.852750736965, 30321.848390757907, 30350.69649303567, 30542.694573054872, 37621.247575048896, 37868.24263514659, 38192.80903595464, 38241.0, 38361.23277534489, 32617.836910815295, 32710.34579308353, 32888.67111328952, 33182.170445738084, 33515.66484335223, 32400.513992289663, 32409.675903240666, 32497.0, 32633.836830815693, 33257.66742332546, 32759.0, 33075.173120672174, 33224.83387583047, 33256.501152483215, 33462.16344591405, 28806.279843004093, 28856.278593035342, 28868.711312886604, 28992.85503572553, 29367.706322937353, 22888.656670149627, 23627.29118126459, 23995.760042399354, 24013.51972960566, 24023.27930162098], "unit": "score", "name": "ext.html", "value": 16458.251112648617}, {"lowerIsBetter": false, "replicates": [350.5390411608733, 351.978881267124, 359.32267675431814, 360.5079067073444, 361.3153074923023, 15535.766963495542, 15852.524424267262, 15867.127307998055, 16003.199840008012, 16230.675386492263, 14912.40350385986, 15102.244887755623, 15104.0, 15145.167015814126, 15262.923685381558, 4952.925706114411, 5015.824446144376, 5114.693118412907, 5132.4610915853855, 5138.8715282118055, 3367.175042114673, 5000.749962501878, 5188.221766735001, 5190.4809519047985, 5199.948000520003, 5160.277561141444, 5167.638265321429, 5172.40517340505, 5173.017126745906, 5183.688978661293, 4969.055879382906, 5112.514311140436, 5142.588592912577, 5149.897002059975, 5156.175011998081, 4925.802967881281, 4940.629452791055, 4942.036302920932, 4947.233178857283, 5020.623453241022, 10764.515596798163, 10789.298695584763, 11959.162858599902, 11982.340971246578, 11986.820197697041, 1865.3750993417223, 1869.8971556564384, 1936.3126090238045, 1982.900854957261, 2055.98972005139, 8582.313414926854, 8720.651173953034, 8747.650093996295, 8867.246284065863, 9232.353735238536, 1696.9915150424292, 1762.8854124481995, 1786.4015554789162, 1808.1320965936293, 1817.291256410001, 57427.712861435844, 60631.19603453749, 61409.77180456411, 61808.0, 62094.0, 9544.33189676716, 9642.566084526214, 9764.560594773253, 9801.16690081351, 9977.201823854075, 13110.868891310965, 13137.34313284332, 13265.535706250235, 13273.203607783564, 13277.0, 7184.461165412615, 7476.102867655916, 7568.924310756932, 7616.619169041636, 7650.923490765021, 7374.9262507375315, 7378.040854688856, 7464.290892365217, 7472.140703819022, 7478.962605187048, 4243.978780106079, 4259.084296876186, 4315.978420107942, 4362.342309922209, 4375.059362237134, 1679.7396403557468, 1686.1990554486522, 1690.5689049292353, 1729.3342063305465, 1763.1536862306166, 814.4909431605247, 846.424431386663, 858.6222062292696, 902.5848109869403, 911.9407238529475, 14457.493987710379, 14642.04826686262, 15299.923500382427, 15563.22183890801, 15754.527364179094, 793.4128744728974, 807.249258189889, 818.7543736878913, 823.2343920154314, 844.5692696724592, 8772.342074344322, 8839.497285461486, 8874.156955089256, 8927.508987005647, 8976.0, 374516.0, 376520.0, 377693.0, 379628.0, 397799.0, 67094.0, 69227.30772692208, 70746.29253707397, 71929.6403517979, 73070.63464682642, 948.2461443152711, 985.2118305355687, 993.1260490768078, 1003.7139415266614, 1012.9442880641489, 14461.276936152939, 14634.121952682874, 14858.851411485746, 15144.394224230908, 15304.693906122038, 32811.0, 33445.33109337751, 34006.3198736019, 34288.485672715426, 34738.131546711535, 47179.52820471889, 47351.0, 47479.28781068217, 47500.28749568828, 47941.041179175525, 6917.9308206917285, 6972.755953541602, 6977.581345119309, 7055.223925368308, 7186.640667966685, 9531.666391676536, 11335.263207891461, 11618.128640351839, 11647.883521165018, 11702.531898724295, 10138.138258248131, 10171.389716617026, 10191.439470829175, 10368.75574931013, 10510.159187264848, 9914.801703965735, 10116.342437741523, 10132.544035518273, 10235.84646230322, 10268.948655256676, 10308.948455257976, 10348.741281468023, 10492.003259690317, 10544.0, 10629.415382154055, 7019.824504387431, 7061.2232654409, 7159.212486626571, 7168.856622867618, 7241.891371629536, 7169.641517923978, 9300.953495232481, 10083.747906302107, 10318.535665894902, 10329.586816527255], "unit": "score", "name": "jquery.html", "value": 7757.319448779769}, {"lowerIsBetter": false, "replicates": [1272.5800485839675, 1360.8298962629672, 1378.3315092180292, 1499.940002399904, 1729.5676080979763, 6540.247871494779, 6684.398404143626, 6825.146856642919, 6826.28324025977, 6834.863302733942, 7141.428685705144, 7295.963520182391, 7296.270372962701, 7304.853902921938, 7314.707411703525, 1895.0430032833399, 2127.181035301405, 2144.238795227699, 2175.260411460103, 2179.8147157491553, 2121.395402310342, 2121.395402310342, 2126.606577783115, 2133.040131940625, 2133.1680644548637, 2044.3969029136367, 2171.1315473810523, 2184.7487538932987, 2187.628103222448, 2194.6049711052005, 2107.4942013916702, 2130.169233998734, 2132.6374516332185, 2145.066895900273, 2149.2047942261306, 2105.5578328551023, 2121.7029615853794, 2127.8936053197276, 2128.0955593872695, 2129.446343950584, 7161.606111663908, 7172.282771722838, 7211.459140564479, 7219.711211551532, 7231.385332246766, 4470.26240670289, 4601.44782626087, 4603.378543896564, 4606.193916064675, 4608.33179189019, 2908.171171216193, 2925.107842108156, 2980.8360540170283, 2981.7465515431213, 3024.500957342036, 908.2506931781306, 909.6452383570413, 915.8900931888217, 929.7861491856843, 937.9062093790635, 10155.136813370873, 10174.338667986558, 10177.592896284139, 10203.4388108654, 10214.540345684461, 4000.399940009022, 4083.509978802503, 4100.507939047334, 4154.376843473503, 4155.709100362946, 8771.342149338698, 8795.604197811113, 8798.648054077765, 8800.207981281588, 8806.251468625238, 3834.0989867381145, 4527.909441811145, 4532.138893610204, 4568.88577785558, 4572.7942242599165, 3930.783806890591, 4038.4749982502085, 4060.6548443382053, 4064.349704047338, 4066.512018557792, 3361.1933136047173, 3428.6057103432927, 3446.431338829065, 3500.5274287970797, 3529.94705079424, 1132.6318946342308, 1153.8211577205548, 1154.468944285638, 1168.3924359333269, 1172.9237599556002, 1190.6249531397755, 1202.5851081376911, 1208.5649166300125, 1211.5335595795507, 1226.0, 6787.830304242433, 6971.721131154798, 7032.578045317195, 7054.470914681419, 7070.222275549685, 432.07104724841844, 439.5780051150867, 444.62207123945035, 445.2253079641401, 445.5232900796117, 5303.655262407931, 5392.325959255093, 5399.487048730364, 5401.864953376197, 5429.049916264717, 8192.75421737349, 8262.752117436487, 8592.656293748305, 8672.089430609822, 9095.045020272775, 8450.535220562928, 8493.320534357237, 8600.78498037554, 8604.440711353742, 8605.741827745178, 6624.668766561653, 6715.328467153343, 6799.184097908283, 6817.250102488722, 6907.620080895699, 6841.726330946706, 6891.724331026703, 6908.896366554405, 6924.169099708067, 6938.8612227756175, 7700.961495192488, 7764.22357764219, 8086.6361013755695, 8107.4324797263625, 8113.391495637969, 7680.500767450098, 7966.203379661987, 8095.919040809516, 8096.068952070587, 8237.382196335178, 5830.0, 5918.408159184047, 6100.420460056288, 6378.840528986812, 6512.511561632802, 6559.0817285581015, 6576.8355791105605, 6599.835004124743, 6613.0, 6619.205695316593, 6001.699915004145, 6196.628202307876, 6197.349278325711, 6324.272708638566, 6553.901691474536, 6085.4523092921845, 6125.0, 6138.877222455437, 6202.131701561695, 6247.5939063960695, 6613.074169616161, 6629.635370054693, 6670.366315200048, 6679.23188833271, 6685.933140668726, 6043.637381757108, 6087.147799308012, 6091.451769340781, 6092.055731361646, 6097.176881120992, 5729.140628905698, 5830.82507524775, 5841.50347220474, 5847.152162936265, 5851.970740146272], "unit": "score", "name": "mootools.html", "value": 4102.514360979987}, {"lowerIsBetter": false, "replicates": [301.38066273807317, 304.847576211894, 305.1455923414439, 307.9722824945756, 308.16948324266104, 5232.816851410196, 5306.655067420625, 5329.573634109272, 5342.946570534294, 5366.114591092475, 5225.268462415265, 5254.658447200929, 5283.207518872162, 5303.575713942885, 5309.362876454821, 3847.057470919615, 3977.7414468059483, 4109.506859176903, 4138.172365526899, 4148.751074935499, 4112.136451345206, 4120.938185927213, 4121.155163191554, 4123.484564429446, 4129.091599848044, 4073.348264277716, 4077.694172937027, 4081.510218773751, 4083.673306135502, 4090.1206240658253, 3817.10298079951, 3883.436901649245, 3891.610838916114, 3945.4081887716784, 4081.795910204493, 3415.692587667097, 3469.705075068622, 3473.9578126561933, 3476.704480119193, 3483.599386070609, 5065.873353166164, 5067.87330316745, 5075.543201111917, 5078.720670363128, 5082.898342033139, 4627.722336659811, 4628.44458664959, 4632.166210082184, 4636.585841318397, 4638.489766125757, 5564.33228012637, 5564.638298510584, 5566.721663916788, 5574.721263936827, 5575.721213939287, 3825.1775868188197, 3834.482344883433, 3838.9424158637644, 3840.8463661453507, 3841.308564458396, 5686.402927692574, 5776.913346299809, 5855.09246066856, 5906.616069955439, 5922.763089476416, 3940.8817735467983, 3946.506686664167, 3949.881503554898, 3966.147278335172, 3968.464257325282, 4011.4785077940264, 4011.859584914514, 4013.8795836124964, 4016.8995775105855, 4022.1754540319166, 4621.768911554409, 4622.884427889262, 4627.768611569475, 4630.143423466669, 4639.721616703008, 5587.161925711176, 5589.161625756169, 5589.944100558942, 5593.0703907151965, 5600.467955544217, 1394.400407824645, 1422.49501426994, 1422.9501967431297, 1423.0678905317034, 1425.4369524037947, 908.9590968406437, 943.5848226780195, 953.5851904421598, 966.2751987701919, 969.2827307792184, 4710.128626204095, 4774.212254977959, 4864.878378040577, 4881.51184881509, 4905.656604037755, 5821.0, 5850.47345738877, 5854.5901786875365, 5855.092460668603, 5861.17943487912, 4919.655624106278, 4933.605311575066, 4938.58022068121, 4940.431850337257, 4942.481039490874, 3074.1085085325453, 3123.390938766964, 3123.515855042472, 3126.89055883043, 3129.4992801151707, 5495.0, 5495.340559132929, 5513.117901135799, 5515.034868897927, 5518.420565840609, 5809.419058094241, 5975.043992961106, 5991.9700401498585, 6007.189029480965, 6008.279006519246, 5483.561315094783, 5510.752016159283, 5513.050517973025, 5513.917291240635, 5515.503604675679, 5834.562407819346, 5843.0, 5849.327327357409, 5855.765769369348, 5856.297244330707, 5725.141228815711, 5732.197492350991, 5741.311042674905, 5745.770169193185, 5745.8563535911935, 5501.4223506532835, 5513.200585914939, 5525.419830917856, 5526.668399896019, 5526.778928842801, 3809.0, 3940.1528671334654, 3944.5069366329208, 3981.8407263709128, 4029.6776257899883, 5633.014222510962, 5697.914531282117, 5723.542116630742, 5746.195532625352, 5778.56660750437, 5562.0, 5577.191307260506, 5578.386377498391, 5578.470045345685, 5588.497035266845, 5562.165675148598, 5572.164175373727, 5573.693446860299, 5586.134149206717, 5595.608307418442, 5492.69790161529, 5504.284443022381, 5506.697131657797, 5510.8071217507195, 5512.173174023928, 5400.540954018952, 5614.5789065821045, 5625.493705566519, 5673.716314184357, 5677.403872593318, 5504.779808807602, 5529.972350138223, 5533.612647114664, 5534.695591742493, 5541.612087153861], "unit": "score", "name": "prototype.html", "value": 4176.232878021095}, {"lowerIsBetter": false, "replicates": [727.52210146548, 748.5620911766616, 773.2653978720217, 777.3819813248467, 785.0853755374989, 12330.876691233085, 12516.687082822935, 12624.621261362163, 12625.0, 12642.0, 11993.0, 12014.399280035985, 12051.216670916385, 12141.57504487343, 12190.695232619168, 634.9460295874833, 793.0364607002476, 800.7237503061434, 821.3880658909121, 822.3010441125056, 812.975610731679, 813.5606772342958, 815.9714409995652, 816.2164322250646, 817.1950628630794, 855.6919508976763, 861.0958493581745, 861.6079683743912, 861.8448679237734, 863.0204717645455, 669.4142625202948, 840.3529282452508, 850.8853402043343, 851.638053827121, 852.7484392104344, 620.1442010026135, 620.4756980351596, 620.6090163197157, 620.7330847735471, 621.0125899819309, 12130.514779408813, 12188.51245950161, 12192.695182620417, 12261.877381226162, 12288.631341059783, 12223.877761222362, 12459.439325230387, 12661.620151395473, 12715.55495557651, 12755.107142499935, 9864.852027219598, 9935.751606209831, 9948.651797187063, 10177.643782467578, 10228.232882533839, 3298.175456135966, 3305.289362787012, 3336.7831090979007, 3366.0575038989123, 3409.6590340965954, 44135.55864441347, 44147.0, 44438.33342499865, 44470.0, 44550.55449445529, 10044.146247568893, 10078.143357814668, 10136.594536218468, 10233.1301839343, 10333.0, 17897.37359192422, 18073.0, 18087.36694215696, 18146.0, 18214.72677909806, 6687.197536295578, 6780.69486873082, 6787.660616969132, 6893.0, 7034.3669069782945, 3746.325661380923, 3749.9625003750157, 3761.962380376216, 3838.7504812187117, 3892.2799282132896, 2825.8304501729963, 2877.798554101235, 2878.553824157259, 2888.162432894437, 2889.4221155768932, 510.6221396166884, 513.4429144378316, 516.5879366782349, 524.8792777661083, 527.9498447647468, 564.6188822544766, 593.7328202308978, 606.6451126091198, 614.6834380294152, 624.4754406298731, 7922.6416130981215, 8190.058143313476, 8202.958985205036, 8403.831923361498, 8493.405461617751, 624.5378419969281, 627.9466245369194, 638.543441439373, 646.599108552691, 652.3769799841141, 3657.213699054716, 3694.131879008431, 3759.285735710206, 3841.654251117413, 3850.4224366345275, 47749.76125119352, 48559.757201214474, 48933.0, 48992.265116023285, 49016.26475602797, 40569.59430405658, 40571.7971410141, 40898.59101408948, 41191.7940410296, 41199.794001029804, 36073.63926360703, 36119.458208126896, 36330.818345908105, 36493.27013459716, 41047.58952410497, 7435.330820226206, 7457.142428620561, 7485.0, 7627.847443051219, 7989.640466178919, 31804.840975794974, 32086.51870221902, 32385.35229295448, 33218.5017224737, 33259.33481330312, 37465.0, 38048.239035219696, 39159.0, 41158.0, 41214.58785412108, 10120.63371444875, 10151.340162889388, 10253.74365640865, 10266.0, 10524.684259472227, 9930.304878658713, 10985.395803230898, 11192.944035279772, 11336.886631133582, 11728.354940478355, 10417.16662667, 10962.177836662122, 11097.500612472619, 11130.387828669502, 11153.051990580567, 10544.156467482737, 10564.471776411303, 10696.572137114428, 11083.72290692739, 11320.433978300887, 10750.0, 10839.67480975572, 11050.502727377449, 11081.722956926142, 11091.722706932069, 9089.727308180765, 9437.858432123661, 9599.664011759554, 9622.855657165008, 9897.752556186153, 9625.374350667184, 9718.854217186888, 9860.802783944424, 9944.105030547285, 10049.799004020026], "unit": "score", "name": "yui.html", "value": 5914.461177920517}], "name": "dromaeo_css", "value": 6228.3963820020435}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 114.0, 107.0, 112.0, 114.0, 114.0, 100.0, 112.0, 111.0, 113.0], "unit": "score", "name": "ai-astar", "value": 111.0}, {"lowerIsBetter": true, "replicates": [158.0, 130.0, 159.0, 176.0, 132.0, 161.0, 153.0, 132.0, 171.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 150.4}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 162.0, 159.0, 159.0, 161.0, 161.0, 162.0, 163.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 99.0, 100.0, 74.0, 74.0, 74.0, 110.0, 106.0, 78.0], "unit": "score", "name": "audio-fft", "value": 86.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 144.0, 95.0, 94.0, 93.0, 145.0, 94.0, 94.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 108.5}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 116.9}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 64.0, 64.0, 63.0, 64.0, 63.0, 74.0, 63.0, 62.0], "unit": "score", "name": "json-parse-financial", "value": 64.9}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 63.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.0}, {"lowerIsBetter": true, "replicates": [89.0, 81.0, 82.0, 84.0, 89.0, 81.0, 88.0, 81.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.0}, {"lowerIsBetter": true, "replicates": [117.0, 125.0, 126.0, 123.0, 117.0, 118.0, 131.0, 124.0, 121.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.0}, {"lowerIsBetter": true, "replicates": [138.0, 142.0, 138.0, 139.0, 138.0, 127.0, 139.0, 137.0, 139.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.7}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 68.0, 70.0, 68.0, 67.0, 68.0, 67.0, 69.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1515.2000000000003}]} 02:42:48 INFO - Return code: 0 02:42:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:42:48 ERROR - # TBPL SUCCESS # 02:42:48 INFO - Running post-action listener: _resource_record_post_action 02:42:48 INFO - Running post-run listener: _resource_record_post_run 02:42:49 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 517849088; Read time: 0; Write time: 488000 02:42:49 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 232562688; Read time: 0; Write time: 273628 02:42:49 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 284688384; Read time: 0; Write time: 214372 02:42:49 INFO - Running post-run listener: _upload_blobber_files 02:42:49 INFO - Blob upload gear active. 02:42:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:42:49 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:42:49 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:42:49 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:42:50 INFO - (blobuploader) - INFO - Open directory for files ... 02:42:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:42:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:42:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:51 INFO - (blobuploader) - INFO - Done attempting. 02:42:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:42:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:42:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:52 INFO - (blobuploader) - INFO - Done attempting. 02:42:52 INFO - (blobuploader) - INFO - Iteration through files over. 02:42:52 INFO - Return code: 0 02:42:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:42:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:42:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/12324aa0c14c7d28edeacb6435eeeb51c29c9ca909322e46d7851d6f36515c2fa0f040f2d913fc82dbf34dd2d419807eb965a78428608984fcbd9fbc6fe19577", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/07fd4a62ed284cf11cd4c2b62484970e055ba04f61e854104974dfc7c4c75fb9731300a9e84805e9c443118fcca6244dcbdd78173f5b2f435cb0b9c5bf5d2b05"} 02:42:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:42:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:42:52 INFO - Contents: 02:42:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/12324aa0c14c7d28edeacb6435eeeb51c29c9ca909322e46d7851d6f36515c2fa0f040f2d913fc82dbf34dd2d419807eb965a78428608984fcbd9fbc6fe19577", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/07fd4a62ed284cf11cd4c2b62484970e055ba04f61e854104974dfc7c4c75fb9731300a9e84805e9c443118fcca6244dcbdd78173f5b2f435cb0b9c5bf5d2b05"} 02:42:52 INFO - Running post-run listener: copy_logs_to_upload_dir 02:42:52 INFO - Copying logs to upload dir... 02:42:52 INFO - mkdir: /builds/slave/test/build/upload/logs 02:42:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.245673 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-06 02:42:51.611998) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-06 02:42:51.615059) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/12324aa0c14c7d28edeacb6435eeeb51c29c9ca909322e46d7851d6f36515c2fa0f040f2d913fc82dbf34dd2d419807eb965a78428608984fcbd9fbc6fe19577", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/07fd4a62ed284cf11cd4c2b62484970e055ba04f61e854104974dfc7c4c75fb9731300a9e84805e9c443118fcca6244dcbdd78173f5b2f435cb0b9c5bf5d2b05"} build_url:https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/ZF2xw40VT_K8fhQkNLD1NQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/12324aa0c14c7d28edeacb6435eeeb51c29c9ca909322e46d7851d6f36515c2fa0f040f2d913fc82dbf34dd2d419807eb965a78428608984fcbd9fbc6fe19577", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/07fd4a62ed284cf11cd4c2b62484970e055ba04f61e854104974dfc7c4c75fb9731300a9e84805e9c443118fcca6244dcbdd78173f5b2f435cb0b9c5bf5d2b05"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-06 02:42:51.639527) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:42:51.639855) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1459934245.928912-1580603307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-06 02:42:51.726179) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-06 02:42:51.726646) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-06 02:42:51.727019) ========= ========= Total master_lag: 0.33 =========