builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1487652806.6 results: success (0) revision: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.596274) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.596854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.597284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.616567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.616968) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 20:53:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-02-20 20:53:27 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150083 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.781174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.781618) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021254 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.831069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:26.831443) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 --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=88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 20:53:27,334 truncating revision to first 12 chars 2017-02-20 20:53:27,334 Setting DEBUG logging. 2017-02-20 20:53:27,335 attempt 1/10 2017-02-20 20:53:27,335 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/276bd9ed3dbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 20:53:27,740 unpacking tar archive at: mozilla-central-276bd9ed3dbf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.729767 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:27.574062) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:27.574371) ========= script_repo_revision: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:27.574723) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:27.574980) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:27.582519) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-20 20:53:27.582849) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:53:28 INFO - MultiFileLogger online at 20170220 20:53:28 in /builds/slave/test 20:53:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 20:53:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:53:28 INFO - {'append_to_log': False, 20:53:28 INFO - 'base_work_dir': '/builds/slave/test', 20:53:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:53:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:53:28 INFO - 'branch': 'Firefox-Non-PGO', 20:53:28 INFO - 'buildbot_json_path': 'buildprops.json', 20:53:28 INFO - 'config_files': ('talos/linux_config.py',), 20:53:28 INFO - 'default_actions': ('clobber', 20:53:28 INFO - 'read-buildbot-config', 20:53:28 INFO - 'download-and-extract', 20:53:28 INFO - 'populate-webroot', 20:53:28 INFO - 'create-virtualenv', 20:53:28 INFO - 'install', 20:53:28 INFO - 'run-tests'), 20:53:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:53:28 INFO - 'download_minidump_stackwalk': True, 20:53:28 INFO - 'download_symbols': 'ondemand', 20:53:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:53:28 INFO - 'tooltool.py': '/tools/tooltool.py', 20:53:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:53:28 INFO - '/tools/misc-python/virtualenv.py')}, 20:53:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:53:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:53:28 INFO - 'gecko_profile': False, 20:53:28 INFO - 'gecko_profile_interval': 0, 20:53:28 INFO - 'installer_path': 'installer.exe', 20:53:28 INFO - 'log_level': 'info', 20:53:28 INFO - 'log_name': 'talos', 20:53:28 INFO - 'log_to_console': True, 20:53:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:53:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:53:28 INFO - 'opt_config_files': (), 20:53:28 INFO - 'pip_index': False, 20:53:28 INFO - 'suite': 'dromaeojs-e10s', 20:53:28 INFO - 'system_bits': '32', 20:53:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:53:28 INFO - 'title': 'talos-linux64-ix-044', 20:53:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:53:28 INFO - 'use_talos_json': True, 20:53:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:53:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:53:28 INFO - 'work_dir': 'build'} 20:53:28 INFO - [mozharness: 2017-02-21 04:53:28.175647Z] Running clobber step. 20:53:28 INFO - Running pre-action listener: _resource_record_pre_action 20:53:28 INFO - Running main action method: clobber 20:53:28 INFO - rmtree: /builds/slave/test/build 20:53:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:53:29 INFO - Running post-action listener: _resource_record_post_action 20:53:29 INFO - [mozharness: 2017-02-21 04:53:29.614322Z] Finished clobber step (success) 20:53:29 INFO - [mozharness: 2017-02-21 04:53:29.614419Z] Running read-buildbot-config step. 20:53:29 INFO - Running pre-action listener: _resource_record_pre_action 20:53:29 INFO - Running main action method: read_buildbot_config 20:53:29 INFO - Using buildbot properties: 20:53:29 INFO - { 20:53:29 INFO - "project": "", 20:53:29 INFO - "product": "firefox", 20:53:29 INFO - "who": "philringnalda@gmail.com", 20:53:29 INFO - "installer_path": "public/build/target.tar.bz2", 20:53:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:53:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:53:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:53:29 INFO - "stage_platform": "linux64", 20:53:29 INFO - "basedir": "/builds/slave/test", 20:53:29 INFO - "buildnumber": 707, 20:53:29 INFO - "platform": "ubuntu64_hw", 20:53:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:53:29 INFO - "slavebuilddir": "test", 20:53:29 INFO - "taskId": "fXh95hwVS4eeCg-rhqTC7g", 20:53:29 INFO - "branch": "mozilla-central", 20:53:29 INFO - "script_repo_revision": "production", 20:53:29 INFO - "revision": "276bd9ed3dbf85f4a6dac67df54a5f2631804de5", 20:53:29 INFO - "slavename": "talos-linux64-ix-044", 20:53:29 INFO - "repo_path": "mozilla-central" 20:53:29 INFO - } 20:53:29 INFO - Finding installer, test and symbols from parent task. 20:53:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXh95hwVS4eeCg-rhqTC7g'}, attempt #1 20:53:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXh95hwVS4eeCg-rhqTC7g'}, attempt #1 20:53:30 INFO - Task dependencies: Bhgef52NTumuZTqjW5EH9g 20:53:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g'}, attempt #1 20:53:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/test_packages.json 20:53:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip 20:53:30 INFO - Running post-action listener: _resource_record_post_action 20:53:30 INFO - [mozharness: 2017-02-21 04:53:30.993668Z] Finished read-buildbot-config step (success) 20:53:30 INFO - [mozharness: 2017-02-21 04:53:30.993883Z] Running download-and-extract step. 20:53:30 INFO - Running pre-action listener: _resource_record_pre_action 20:53:30 INFO - Running main action method: download_and_extract 20:53:30 INFO - mkdir: /builds/slave/test/build/tests 20:53:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:30 INFO - trying https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.test_packages.json 20:53:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:53:31 INFO - Downloaded 896 bytes. 20:53:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:53:31 INFO - Using the following test package requirements: 20:53:31 INFO - {u'common': [u'target.common.tests.zip'], 20:53:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:53:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:53:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:53:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:53:31 INFO - u'mozbase': [u'target.common.tests.zip'], 20:53:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:53:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:53:31 INFO - u'web-platform': [u'target.common.tests.zip', 20:53:31 INFO - u'target.web-platform.tests.zip'], 20:53:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:53:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:53:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip 20:53:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:53:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip into memory 20:53:32 INFO - Expected file size: 57165246 20:53:32 INFO - Obtained file size: 57165246 20:53:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:53:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip 20:53:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:53:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip into memory 20:53:43 INFO - Expected file size: 57165246 20:53:43 INFO - Obtained file size: 57165246 20:53:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.talos.tests.zip 20:53:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:53:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.talos.tests.zip into memory 20:53:54 INFO - Expected file size: 14404319 20:53:54 INFO - Obtained file size: 14404319 20:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:54 INFO - trying https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:53:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:53:55 INFO - Downloaded 61459462 bytes. 20:53:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:55 INFO - mkdir: /builds/slave/test/properties 20:53:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:53:55 INFO - Writing to file /builds/slave/test/properties/build_url 20:53:55 INFO - Contents: 20:53:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:55 INFO - Running post-action listener: _resource_record_post_action 20:53:55 INFO - Running post-action listener: set_extra_try_arguments 20:53:55 INFO - [mozharness: 2017-02-21 04:53:55.901460Z] Finished download-and-extract step (success) 20:53:55 INFO - [mozharness: 2017-02-21 04:53:55.901557Z] Running populate-webroot step. 20:53:55 INFO - Running pre-action listener: _resource_record_pre_action 20:53:55 INFO - Running main action method: populate_webroot 20:53:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:53:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:53:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:53:55 INFO - '--tppagecycles', 20:53:55 INFO - '7'], 20:53:55 INFO - 'tests': ['tsvgm']}}, 20:53:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:53:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:53:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:53:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:53:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:53:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:53:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['damp', 'tps']}, 20:53:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'tests': ['damp', 'tps']}, 20:53:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['dromaeo_dom']}, 20:53:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:53:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['basic_compositor_video']}, 20:53:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:53:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['a11yr', 20:53:55 INFO - 'ts_paint', 20:53:55 INFO - 'tpaint', 20:53:55 INFO - 'sessionrestore', 20:53:55 INFO - 'sessionrestore_no_auto_restore', 20:53:55 INFO - 'tabpaint']}, 20:53:55 INFO - 'other-e10s': {'tests': ['a11yr', 20:53:55 INFO - 'ts_paint', 20:53:55 INFO - 'tpaint', 20:53:55 INFO - 'sessionrestore', 20:53:55 INFO - 'sessionrestore_no_auto_restore', 20:53:55 INFO - 'tabpaint']}, 20:53:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:53:55 INFO - 'ts_paint', 20:53:55 INFO - 'tpaint', 20:53:55 INFO - 'sessionrestore', 20:53:55 INFO - 'sessionrestore_no_auto_restore', 20:53:55 INFO - 'tabpaint']}, 20:53:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:53:55 INFO - 'ts_paint', 20:53:55 INFO - 'tpaint', 20:53:55 INFO - 'sessionrestore', 20:53:55 INFO - 'sessionrestore_no_auto_restore', 20:53:55 INFO - 'tabpaint']}, 20:53:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['a11yr', 20:53:55 INFO - 'ts_paint', 20:53:55 INFO - 'tpaint', 20:53:55 INFO - 'sessionrestore', 20:53:55 INFO - 'sessionrestore_no_auto_restore', 20:53:55 INFO - 'tabpaint']}, 20:53:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['a11yr', 20:53:55 INFO - 'ts_paint', 20:53:55 INFO - 'tpaint', 20:53:55 INFO - 'sessionrestore', 20:53:55 INFO - 'sessionrestore_no_auto_restore', 20:53:55 INFO - 'tabpaint']}, 20:53:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['tsvgx', 20:53:55 INFO - 'tsvgr_opacity', 20:53:55 INFO - 'tart', 20:53:55 INFO - 'tscrollx', 20:53:55 INFO - 'cart', 20:53:55 INFO - 'tsvg_static']}, 20:53:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:53:55 INFO - 'tsvgr_opacity', 20:53:55 INFO - 'tart', 20:53:55 INFO - 'tscrollx', 20:53:55 INFO - 'cart', 20:53:55 INFO - 'tsvg_static']}, 20:53:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'talos_options': ['--disable-e10s'], 20:53:55 INFO - 'tests': ['tp5o']}, 20:53:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:53:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'talos_options': ['--disable-e10s', 20:53:55 INFO - '--xperf_path', 20:53:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:53:55 INFO - 'tests': ['tp5n']}, 20:53:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:53:55 INFO - 'talos_options': ['--xperf_path', 20:53:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:53:55 INFO - 'tests': ['tp5n']}}, 20:53:55 INFO - 'talos.zip': {'path': '', 20:53:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:53:55 INFO - Running post-action listener: _resource_record_post_action 20:53:55 INFO - [mozharness: 2017-02-21 04:53:55.914711Z] Finished populate-webroot step (success) 20:53:55 INFO - [mozharness: 2017-02-21 04:53:55.914800Z] Running create-virtualenv step. 20:53:55 INFO - Running pre-action listener: _resource_record_pre_action 20:53:55 INFO - Running main action method: create_virtualenv 20:53:55 INFO - Creating virtualenv /builds/slave/test/build/venv 20:53:55 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 20:53:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:53:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:53:56 INFO - Using real prefix '/usr' 20:53:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:53:56 INFO - Installing distribute.............................................................................................................................................................................................done. 20:53:58 INFO - Installing pip.................done. 20:53:58 INFO - Return code: 0 20:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:53:58 INFO - Reading from file tmpfile_stdout 20:53:58 INFO - Output received: 20:53:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:53:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:53:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53:58 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 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:53:58 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 20:53:58 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 20:53:58 INFO - Using env: {'DISPLAY': ':0', 20:53:58 INFO - 'HOME': '/home/cltbld', 20:53:58 INFO - 'LANG': 'en_US.UTF-8', 20:53:58 INFO - 'LANGUAGE': 'en_US:en', 20:53:58 INFO - 'LOGNAME': 'cltbld', 20:53:58 INFO - 'MAIL': '/var/mail/cltbld', 20:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:53:58 INFO - 'MOZ_NO_REMOTE': '1', 20:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:53:58 INFO - 'NO_EM_RESTART': '1', 20:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:53:58 INFO - 'PWD': '/builds/slave/test', 20:53:58 INFO - 'SHELL': '/bin/bash', 20:53:58 INFO - 'SHLVL': '1', 20:53:58 INFO - 'TERM': 'linux', 20:53:58 INFO - 'TMOUT': '86400', 20:53:58 INFO - 'USER': 'cltbld', 20:53:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:53:58 INFO - '_': '/tools/buildbot/bin/python'} 20:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:53:58 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 20:53:58 INFO - Cleaning up... 20:53:58 INFO - Return code: 0 20:53:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:53:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:53:58 INFO - Using env: {'DISPLAY': ':0', 20:53:58 INFO - 'HOME': '/home/cltbld', 20:53:58 INFO - 'LANG': 'en_US.UTF-8', 20:53:58 INFO - 'LANGUAGE': 'en_US:en', 20:53:58 INFO - 'LOGNAME': 'cltbld', 20:53:58 INFO - 'MAIL': '/var/mail/cltbld', 20:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:53:58 INFO - 'MOZ_NO_REMOTE': '1', 20:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:53:58 INFO - 'NO_EM_RESTART': '1', 20:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:53:58 INFO - 'PWD': '/builds/slave/test', 20:53:58 INFO - 'SHELL': '/bin/bash', 20:53:58 INFO - 'SHLVL': '1', 20:53:58 INFO - 'TERM': 'linux', 20:53:58 INFO - 'TMOUT': '86400', 20:53:58 INFO - 'USER': 'cltbld', 20:53:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:53:58 INFO - '_': '/tools/buildbot/bin/python'} 20:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:53:59 INFO - Downloading/unpacking psutil>=3.1.1 20:53:59 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 20:53:59 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 20:53:59 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 20:53:59 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 20:53:59 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 20:53:59 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 20:54:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:54:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:54:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:54:01 INFO - Installing collected packages: psutil 20:54:01 INFO - Running setup.py install for psutil 20:54:01 INFO - building 'psutil._psutil_linux' extension 20:54:01 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 20:54:01 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 20:54:01 INFO - building 'psutil._psutil_posix' extension 20:54:01 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 20:54:01 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 20:54:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:54:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:54:01 INFO - Successfully installed psutil 20:54:01 INFO - Cleaning up... 20:54:01 INFO - Return code: 0 20:54:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:54:01 INFO - Using env: {'DISPLAY': ':0', 20:54:01 INFO - 'HOME': '/home/cltbld', 20:54:01 INFO - 'LANG': 'en_US.UTF-8', 20:54:01 INFO - 'LANGUAGE': 'en_US:en', 20:54:01 INFO - 'LOGNAME': 'cltbld', 20:54:01 INFO - 'MAIL': '/var/mail/cltbld', 20:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:01 INFO - 'MOZ_NO_REMOTE': '1', 20:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:01 INFO - 'NO_EM_RESTART': '1', 20:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:01 INFO - 'PWD': '/builds/slave/test', 20:54:01 INFO - 'SHELL': '/bin/bash', 20:54:01 INFO - 'SHLVL': '1', 20:54:01 INFO - 'TERM': 'linux', 20:54:01 INFO - 'TMOUT': '86400', 20:54:01 INFO - 'USER': 'cltbld', 20:54:01 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:01 INFO - '_': '/tools/buildbot/bin/python'} 20:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:54:02 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 20:54:02 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 20:54:02 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 20:54:02 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 20:54:02 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 20:54:02 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 20:54:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:54:04 INFO - Installing collected packages: mozsystemmonitor 20:54:04 INFO - Running setup.py install for mozsystemmonitor 20:54:04 INFO - Successfully installed mozsystemmonitor 20:54:04 INFO - Cleaning up... 20:54:04 INFO - Return code: 0 20:54:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:54:04 INFO - Using env: {'DISPLAY': ':0', 20:54:04 INFO - 'HOME': '/home/cltbld', 20:54:04 INFO - 'LANG': 'en_US.UTF-8', 20:54:04 INFO - 'LANGUAGE': 'en_US:en', 20:54:04 INFO - 'LOGNAME': 'cltbld', 20:54:04 INFO - 'MAIL': '/var/mail/cltbld', 20:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:04 INFO - 'MOZ_NO_REMOTE': '1', 20:54:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:04 INFO - 'NO_EM_RESTART': '1', 20:54:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:04 INFO - 'PWD': '/builds/slave/test', 20:54:04 INFO - 'SHELL': '/bin/bash', 20:54:04 INFO - 'SHLVL': '1', 20:54:04 INFO - 'TERM': 'linux', 20:54:04 INFO - 'TMOUT': '86400', 20:54:04 INFO - 'USER': 'cltbld', 20:54:04 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:04 INFO - '_': '/tools/buildbot/bin/python'} 20:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:04 INFO - Downloading/unpacking jsonschema==2.5.1 20:54:04 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 20:54:04 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 20:54:04 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 20:54:04 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 20:54:04 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 20:54:04 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 20:54:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:54:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:06 INFO - Downloading functools32-3.2.3-2.tar.gz 20:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:54:06 INFO - warning: no files found matching '*.txt' 20:54:06 INFO - no previously-included directories found matching 'build' 20:54:06 INFO - no previously-included directories found matching 'dist' 20:54:06 INFO - no previously-included directories found matching '.git*' 20:54:06 INFO - Installing collected packages: jsonschema, functools32 20:54:06 INFO - Running setup.py install for functools32 20:54:06 INFO - warning: no files found matching '*.txt' 20:54:06 INFO - no previously-included directories found matching 'build' 20:54:06 INFO - no previously-included directories found matching 'dist' 20:54:06 INFO - no previously-included directories found matching '.git*' 20:54:06 INFO - Successfully installed jsonschema functools32 20:54:06 INFO - Cleaning up... 20:54:06 INFO - Return code: 0 20:54:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:06 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:06 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:54:06 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 functools32==3.2.3-2 20:54:06 INFO - Using env: {'DISPLAY': ':0', 20:54:06 INFO - 'HOME': '/home/cltbld', 20:54:06 INFO - 'LANG': 'en_US.UTF-8', 20:54:06 INFO - 'LANGUAGE': 'en_US:en', 20:54:06 INFO - 'LOGNAME': 'cltbld', 20:54:06 INFO - 'MAIL': '/var/mail/cltbld', 20:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:06 INFO - 'MOZ_NO_REMOTE': '1', 20:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:06 INFO - 'NO_EM_RESTART': '1', 20:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:06 INFO - 'PWD': '/builds/slave/test', 20:54:06 INFO - 'SHELL': '/bin/bash', 20:54:06 INFO - 'SHLVL': '1', 20:54:06 INFO - 'TERM': 'linux', 20:54:06 INFO - 'TMOUT': '86400', 20:54:06 INFO - 'USER': 'cltbld', 20:54:06 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:06 INFO - '_': '/tools/buildbot/bin/python'} 20:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:54:07 INFO - Cleaning up... 20:54:07 INFO - Return code: 0 20:54:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:54:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:07 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 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:07 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 20:54:07 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 20:54:07 INFO - Using env: {'DISPLAY': ':0', 20:54:07 INFO - 'HOME': '/home/cltbld', 20:54:07 INFO - 'LANG': 'en_US.UTF-8', 20:54:07 INFO - 'LANGUAGE': 'en_US:en', 20:54:07 INFO - 'LOGNAME': 'cltbld', 20:54:07 INFO - 'MAIL': '/var/mail/cltbld', 20:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:07 INFO - 'MOZ_NO_REMOTE': '1', 20:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:07 INFO - 'NO_EM_RESTART': '1', 20:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:07 INFO - 'PWD': '/builds/slave/test', 20:54:07 INFO - 'SHELL': '/bin/bash', 20:54:07 INFO - 'SHLVL': '1', 20:54:07 INFO - 'TERM': 'linux', 20:54:07 INFO - 'TMOUT': '86400', 20:54:07 INFO - 'USER': 'cltbld', 20:54:07 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:07 INFO - '_': '/tools/buildbot/bin/python'} 20:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:07 INFO - Downloading/unpacking blobuploader==1.2.4 20:54:07 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 20:54:07 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 20:54:07 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 20:54:07 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 20:54:07 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 20:54:07 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 20:54:09 INFO - Downloading blobuploader-1.2.4.tar.gz 20:54:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:54:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:54:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:10 INFO - Downloading docopt-0.6.1.tar.gz 20:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:54:10 INFO - Installing collected packages: blobuploader, requests, docopt 20:54:10 INFO - Running setup.py install for blobuploader 20:54:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:54:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:54:10 INFO - Running setup.py install for requests 20:54:10 INFO - Running setup.py install for docopt 20:54:10 INFO - Successfully installed blobuploader requests docopt 20:54:10 INFO - Cleaning up... 20:54:10 INFO - Return code: 0 20:54:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:10 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 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:10 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 20:54:10 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 20:54:10 INFO - Using env: {'DISPLAY': ':0', 20:54:10 INFO - 'HOME': '/home/cltbld', 20:54:10 INFO - 'LANG': 'en_US.UTF-8', 20:54:10 INFO - 'LANGUAGE': 'en_US:en', 20:54:10 INFO - 'LOGNAME': 'cltbld', 20:54:10 INFO - 'MAIL': '/var/mail/cltbld', 20:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:10 INFO - 'MOZ_NO_REMOTE': '1', 20:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:10 INFO - 'NO_EM_RESTART': '1', 20:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:10 INFO - 'PWD': '/builds/slave/test', 20:54:10 INFO - 'SHELL': '/bin/bash', 20:54:10 INFO - 'SHLVL': '1', 20:54:10 INFO - 'TERM': 'linux', 20:54:10 INFO - 'TMOUT': '86400', 20:54:10 INFO - 'USER': 'cltbld', 20:54:10 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:10 INFO - '_': '/tools/buildbot/bin/python'} 20:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:54:11 INFO - Running setup.py (path:/tmp/pip-mlGAU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:54:11 INFO - Running setup.py (path:/tmp/pip-lVhO8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:54:11 INFO - Running setup.py (path:/tmp/pip-e3C7FE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:54:11 INFO - Running setup.py (path:/tmp/pip-eCG8gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:54:11 INFO - Running setup.py (path:/tmp/pip-wg6RRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:54:11 INFO - Running setup.py (path:/tmp/pip-aX8rWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:54:11 INFO - Running setup.py (path:/tmp/pip-RxCU7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:54:11 INFO - Running setup.py (path:/tmp/pip-6MYeCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:54:11 INFO - Running setup.py (path:/tmp/pip-OPPGAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:54:11 INFO - Running setup.py (path:/tmp/pip-tZCF99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:54:11 INFO - Running setup.py (path:/tmp/pip-dpT2Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:54:11 INFO - Running setup.py (path:/tmp/pip-lZ1Iin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:54:11 INFO - Running setup.py (path:/tmp/pip-iC52wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:54:11 INFO - Running setup.py (path:/tmp/pip-Z4x6HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:54:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:12 INFO - Running setup.py (path:/tmp/pip-OG6VjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:54:12 INFO - Running setup.py (path:/tmp/pip-Wi65bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:54:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:54:12 INFO - Running setup.py (path:/tmp/pip-L4oexz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:54:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:54:12 INFO - Running setup.py install for manifestparser 20:54:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozcrash 20:54:12 INFO - Running setup.py install for mozdebug 20:54:12 INFO - Running setup.py install for mozdevice 20:54:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozfile 20:54:12 INFO - Running setup.py install for mozhttpd 20:54:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozinfo 20:54:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozInstall 20:54:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Running setup.py install for mozleak 20:54:13 INFO - Running setup.py install for mozlog 20:54:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Running setup.py install for moznetwork 20:54:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Running setup.py install for mozprocess 20:54:13 INFO - Running setup.py install for mozprofile 20:54:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Running setup.py install for mozrunner 20:54:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:54:13 INFO - Running setup.py install for mozscreenshot 20:54:13 INFO - Running setup.py install for moztest 20:54:13 INFO - Running setup.py install for mozversion 20:54:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:54:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:54:14 INFO - Cleaning up... 20:54:14 INFO - Return code: 0 20:54:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:54:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:54:14 INFO - Using env: {'DISPLAY': ':0', 20:54:14 INFO - 'HOME': '/home/cltbld', 20:54:14 INFO - 'LANG': 'en_US.UTF-8', 20:54:14 INFO - 'LANGUAGE': 'en_US:en', 20:54:14 INFO - 'LOGNAME': 'cltbld', 20:54:14 INFO - 'MAIL': '/var/mail/cltbld', 20:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:14 INFO - 'MOZ_NO_REMOTE': '1', 20:54:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:14 INFO - 'NO_EM_RESTART': '1', 20:54:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:14 INFO - 'PWD': '/builds/slave/test', 20:54:14 INFO - 'SHELL': '/bin/bash', 20:54:14 INFO - 'SHLVL': '1', 20:54:14 INFO - 'TERM': 'linux', 20:54:14 INFO - 'TMOUT': '86400', 20:54:14 INFO - 'USER': 'cltbld', 20:54:14 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:14 INFO - '_': '/tools/buildbot/bin/python'} 20:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:54:14 INFO - Running setup.py (path:/tmp/pip-cxsmke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:54:14 INFO - Running setup.py (path:/tmp/pip-_apk_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:54:14 INFO - Running setup.py (path:/tmp/pip-HmDu3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:54:14 INFO - Running setup.py (path:/tmp/pip-Pyabsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:54:14 INFO - Running setup.py (path:/tmp/pip-nEVMl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:54:14 INFO - Running setup.py (path:/tmp/pip-SS0j2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:54:14 INFO - Running setup.py (path:/tmp/pip-4YJ2FH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:54:14 INFO - Running setup.py (path:/tmp/pip-dvWLrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:54:14 INFO - Running setup.py (path:/tmp/pip-dMK6av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:54:14 INFO - Running setup.py (path:/tmp/pip-ZaW7us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:54:15 INFO - Running setup.py (path:/tmp/pip-yLJ2aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:54:15 INFO - Running setup.py (path:/tmp/pip-QoTMHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:54:15 INFO - Running setup.py (path:/tmp/pip-wGNMot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:54:15 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)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:54:15 INFO - Running setup.py (path:/tmp/pip-ry3cfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:15 INFO - Running setup.py (path:/tmp/pip-03T6hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:15 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)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:54:15 INFO - Running setup.py (path:/tmp/pip-YgFRzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 20:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:54:15 INFO - Running setup.py (path:/tmp/pip-HRwcxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:54:15 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)) 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:54:15 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)) 20:54:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:54:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:54:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:17 INFO - Downloading blessings-1.6.tar.gz 20:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:54:17 INFO - Installing collected packages: blessings 20:54:17 INFO - Running setup.py install for blessings 20:54:17 INFO - Successfully installed blessings 20:54:17 INFO - Cleaning up... 20:54:17 INFO - Return code: 0 20:54:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:54:17 INFO - Reading from file tmpfile_stdout 20:54:17 INFO - Current package versions: 20:54:17 INFO - argparse == 1.2.1 20:54:17 INFO - blessings == 1.6 20:54:17 INFO - blobuploader == 1.2.4 20:54:17 INFO - docopt == 0.6.1 20:54:17 INFO - functools32 == 3.2.3-2 20:54:17 INFO - jsonschema == 2.5.1 20:54:17 INFO - manifestparser == 1.1 20:54:17 INFO - mozInstall == 1.12 20:54:17 INFO - mozcrash == 1.0 20:54:17 INFO - mozdebug == 0.1 20:54:17 INFO - mozdevice == 0.49 20:54:17 INFO - mozfile == 1.2 20:54:17 INFO - mozhttpd == 0.7 20:54:17 INFO - mozinfo == 0.9 20:54:17 INFO - mozleak == 0.1 20:54:17 INFO - mozlog == 3.4 20:54:17 INFO - moznetwork == 0.27 20:54:17 INFO - mozprocess == 0.25 20:54:17 INFO - mozprofile == 0.28 20:54:17 INFO - mozrunner == 6.13 20:54:17 INFO - mozscreenshot == 0.1 20:54:17 INFO - mozsystemmonitor == 0.3 20:54:17 INFO - moztest == 0.8 20:54:17 INFO - mozversion == 1.4 20:54:17 INFO - psutil == 3.1.1 20:54:17 INFO - requests == 1.2.3 20:54:17 INFO - wsgiref == 0.1.2 20:54:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:54:18 INFO - Using env: {'DISPLAY': ':0', 20:54:18 INFO - 'HOME': '/home/cltbld', 20:54:18 INFO - 'LANG': 'en_US.UTF-8', 20:54:18 INFO - 'LANGUAGE': 'en_US:en', 20:54:18 INFO - 'LOGNAME': 'cltbld', 20:54:18 INFO - 'MAIL': '/var/mail/cltbld', 20:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:18 INFO - 'MOZ_NO_REMOTE': '1', 20:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:18 INFO - 'NO_EM_RESTART': '1', 20:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:18 INFO - 'PWD': '/builds/slave/test', 20:54:18 INFO - 'SHELL': '/bin/bash', 20:54:18 INFO - 'SHLVL': '1', 20:54:18 INFO - 'TERM': 'linux', 20:54:18 INFO - 'TMOUT': '86400', 20:54:18 INFO - 'USER': 'cltbld', 20:54:18 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:18 INFO - '_': '/tools/buildbot/bin/python'} 20:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:54:18 INFO - Cleaning up... 20:54:18 INFO - Return code: 0 20:54:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', '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 20:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:54:18 INFO - Using env: {'DISPLAY': ':0', 20:54:18 INFO - 'HOME': '/home/cltbld', 20:54:18 INFO - 'LANG': 'en_US.UTF-8', 20:54:18 INFO - 'LANGUAGE': 'en_US:en', 20:54:18 INFO - 'LOGNAME': 'cltbld', 20:54:18 INFO - 'MAIL': '/var/mail/cltbld', 20:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:18 INFO - 'MOZ_NO_REMOTE': '1', 20:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:18 INFO - 'NO_EM_RESTART': '1', 20:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:18 INFO - 'PWD': '/builds/slave/test', 20:54:18 INFO - 'SHELL': '/bin/bash', 20:54:18 INFO - 'SHLVL': '1', 20:54:18 INFO - 'TERM': 'linux', 20:54:18 INFO - 'TMOUT': '86400', 20:54:18 INFO - 'USER': 'cltbld', 20:54:18 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:18 INFO - '_': '/tools/buildbot/bin/python'} 20:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:54:18 INFO - Cleaning up... 20:54:18 INFO - Return code: 0 20:54:18 INFO - Running post-action listener: _resource_record_post_action 20:54:18 INFO - Running post-action listener: _start_resource_monitoring 20:54:18 INFO - Starting resource monitoring. 20:54:18 INFO - [mozharness: 2017-02-21 04:54:18.490410Z] Finished create-virtualenv step (success) 20:54:18 INFO - [mozharness: 2017-02-21 04:54:18.490923Z] Running install step. 20:54:18 INFO - Running pre-action listener: _resource_record_pre_action 20:54:18 INFO - Running main action method: install 20:54:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:54:18 INFO - Reading from file tmpfile_stdout 20:54:18 INFO - Detecting whether we're running mozinstall >=1.0... 20:54:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:54:18 INFO - Reading from file tmpfile_stdout 20:54:18 INFO - Output received: 20:54:18 INFO - Usage: mozinstall [options] installer 20:54:18 INFO - Options: 20:54:18 INFO - -h, --help show this help message and exit 20:54:18 INFO - -d DEST, --destination=DEST 20:54:18 INFO - Directory to install application into. [default: 20:54:18 INFO - "/builds/slave/test"] 20:54:18 INFO - --app=APP Application being installed. [default: firefox] 20:54:18 INFO - mkdir: /builds/slave/test/build/application 20:54:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:54:34 INFO - Reading from file tmpfile_stdout 20:54:34 INFO - Output received: 20:54:34 INFO - /builds/slave/test/build/application/firefox/firefox 20:54:34 INFO - Running post-action listener: _resource_record_post_action 20:54:34 INFO - [mozharness: 2017-02-21 04:54:34.211877Z] Finished install step (success) 20:54:34 INFO - [mozharness: 2017-02-21 04:54:34.212144Z] Running run-tests step. 20:54:34 INFO - Running pre-action listener: _resource_record_pre_action 20:54:34 INFO - Running main action method: run_tests 20:54:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:54:34 INFO - Python 2.7.3 20:54:34 INFO - Return code: 0 20:54:34 INFO - grabbing minidump binary from tooltool 20:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9b290>, '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 0x1a9bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a9c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:54:34 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 20:54:34 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 20:54:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:54:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:54:34 INFO - Return code: 0 20:54:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:54:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:54:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:54:34 INFO - ENV: RUST_BACKTRACE is now 1 20:54:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:54:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:54:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:54:34 INFO - Using env: {'DISPLAY': ':0', 20:54:34 INFO - 'HOME': '/home/cltbld', 20:54:34 INFO - 'LANG': 'en_US.UTF-8', 20:54:34 INFO - 'LANGUAGE': 'en_US:en', 20:54:34 INFO - 'LOGNAME': 'cltbld', 20:54:34 INFO - 'MAIL': '/var/mail/cltbld', 20:54:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:54:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:34 INFO - 'MOZ_NO_REMOTE': '1', 20:54:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:54:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:34 INFO - 'NO_EM_RESTART': '1', 20:54:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:34 INFO - 'PWD': '/builds/slave/test', 20:54:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:54:34 INFO - 'RUST_BACKTRACE': '1', 20:54:34 INFO - 'SHELL': '/bin/bash', 20:54:34 INFO - 'SHLVL': '1', 20:54:34 INFO - 'TERM': 'linux', 20:54:34 INFO - 'TMOUT': '86400', 20:54:34 INFO - 'USER': 'cltbld', 20:54:34 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447', 20:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:34 INFO - '_': '/tools/buildbot/bin/python'} 20:54:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:54:34 INFO - mozversion application_buildid: 20170221042705 20:54:34 INFO - mozversion application_changeset: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 20:54:34 INFO - mozversion application_display_name: Nightly 20:54:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:54:34 INFO - mozversion application_name: Firefox 20:54:34 INFO - mozversion application_remotingname: firefox 20:54:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:54:34 INFO - mozversion application_vendor: Mozilla 20:54:34 INFO - mozversion application_version: 54.0a1 20:54:34 INFO - mozversion platform_buildid: 20170221042705 20:54:34 INFO - mozversion platform_changeset: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 20:54:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:54:34 INFO - mozversion platform_version: 54.0a1 20:54:34 INFO - using testdate: 1487652874 20:54:34 INFO - actual date: 1487652874 20:54:34 INFO - starting webserver on 'localhost:47145' 20:54:34 INFO - SUITE-START | Running 2 tests 20:54:34 INFO - TEST-START | dromaeo_css 20:54:34 INFO - operating with platform_type : linux_ 20:54:34 INFO - Initialising browser for dromaeo_css test... 20:54:34 INFO - TEST-INFO | started process 23715 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzJVaTl/profile http://localhost:47145/getInfo.html) 20:54:37 INFO - PROCESS | 23715 | Iterating over profile /tmp/tmpzJVaTl/profile 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/places.sqlite-shm = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/crashes = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/minidumps = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/extensions.json = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/sessionCheckpoints.json = Mon Feb 20 2017 20:54:38 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/key3.db = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/permissions.sqlite = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/user.js = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/.parentlock = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/cert8.db = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | __metrics Screen width/height:1600/1200 20:54:38 INFO - PROCESS | 23715 | colorDepth:24 20:54:38 INFO - PROCESS | 23715 | Browser inner width/height: 1024/697 20:54:38 INFO - PROCESS | 23715 | __metrics 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/compatibility.ini = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/places.sqlite = Mon Feb 20 2017 20:54:38 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/startupCache = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/pluginreg.dat = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/directoryLinks.json = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/prefs.js = Mon Feb 20 2017 20:53:54 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/thumbnails = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/addons.json = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/places.sqlite-wal = Mon Feb 20 2017 20:54:38 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | Stat failure 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/safebrowsing = Mon Feb 20 2017 20:54:38 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/secmod.db = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/extensions.ini = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/cache2 = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/localstore.rdf = Mon Feb 20 2017 20:53:54 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/content-prefs.sqlite = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/frequencyCap.json = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/gmp = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/extensions = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/bookmarkbackups = Mon Feb 20 2017 20:54:36 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 23715 | No birth date. Using mtime. 20:54:38 INFO - PROCESS | 23715 | Using date: /tmp/tmpzJVaTl/profile/blocklist.xml = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:39 ERROR - PROCESS | 23715 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:54:40 INFO - TEST-INFO | 23715: exit 0 20:54:40 INFO - Browser initialized. 20:54:40 INFO - Running cycle 1/1 for dromaeo_css test... 20:54:40 INFO - TEST-INFO | started process 23844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzJVaTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:54:52 INFO - PROCESS | 23844 | RSS: Main: 156700672 20:54:52 INFO - PROCESS | 23844 | 20:57:56 INFO - PROCESS | 23844 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47145/tests/dromaeo/cssquery-ext.html) 20:57:58 INFO - PROCESS | 23844 | RSS: Main: 162996224 20:57:58 INFO - PROCESS | 23844 | 20:59:40 INFO - PROCESS | 23844 | ************************* 20:59:40 INFO - PROCESS | 23844 | A coding exception was thrown and uncaught in a Task. 20:59:40 INFO - PROCESS | 23844 | 20:59:40 ERROR - PROCESS | 23844 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:59:40 INFO - PROCESS | 23844 | Full stack: 20:59:40 INFO - PROCESS | 23844 | ************************* 21:01:01 INFO - PROCESS | 23844 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-ext.html (next: http://localhost:47145/tests/dromaeo/cssquery-jquery.html) 21:01:02 INFO - PROCESS | 23844 | RSS: Main: 158597120 21:01:02 INFO - PROCESS | 23844 | 21:04:04 INFO - PROCESS | 23844 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47145/tests/dromaeo/cssquery-mootools.html) 21:04:04 INFO - PROCESS | 23844 | RSS: Main: 166612992 21:04:04 INFO - PROCESS | 23844 | 21:07:07 INFO - PROCESS | 23844 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47145/tests/dromaeo/cssquery-prototype.html) 21:07:07 INFO - PROCESS | 23844 | RSS: Main: 158908416 21:07:07 INFO - PROCESS | 23844 | 21:10:10 INFO - PROCESS | 23844 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47145/tests/dromaeo/cssquery-yui.html) 21:10:11 INFO - PROCESS | 23844 | RSS: Main: 165998592 21:10:11 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-yui.html (next: http://localhost:47145/tests/dromaeo/cssquery-dojo.html) 21:13:14 INFO - PROCESS | 23844 | RSS: Main: 159260672 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | __start_tp_report 21:13:14 INFO - PROCESS | 23844 | _x_x_mozilla_page_load 21:13:14 INFO - PROCESS | 23844 | _x_x_mozilla_page_load_details 21:13:14 INFO - PROCESS | 23844 | |i|pagename|runs| 21:13:14 INFO - PROCESS | 23844 | |0;dojo.html;2601.1546247469573;2614.045873256262;2624.8556329401886;2633.5259653262406;2650.695170055443;4792.089502994429;4803.351547541081;4815.783289751961;4827;4876.756162191885;4816.69949113739;4830.951690483094;4834.178189707749;4919.286703428001;4954.232094025432;4217.448870222277;4436.334549817521;4532.682712210147;4556.430446194226;4559.224931761609;4507.639388848885;4510.571495707903;4515.954840451603;4518.028623845872;4520.2315606347;4343.435353404069;4384.16700826845;4397.252467080604;4443.777811109431;4465.084657645175;4402.185595664812;4411.669124815651;4433.290673492225;4455.287154055368;4459.264221403465;4358.193734159159;4375.781210939472;4387.210302145612;4394.340848872663;4441.956140307026;4612.331211974279;4647.37260469839;4649.930251046237;4701.341812146302;4856.39295088114;5042.1176294148745;5053.8231161909525;5085.74571271435;5085.771140298695;5174.896502069938;5135.974320128413;5139.948600513984;5140.228965655144;5147.4080480744815;5152.9484705152845;4816.181249187646;4821.662483626183;4838.516148385203;4856.295837103636;4869.050535145612;4999.875003124878;5044.344235249394;5300.416954135042;5307.310049693515;5341.65279256847;5131.589472842164;5131.87170320745;5137.717425541557;5141.948580514147;5149.2276158576515;5165.457626949192;5210.557102646318;5226.294450249244;5229.398619158846;5251.422343542207;4849.975750121227;4876.609871210295;4895.73073480962;4952.504749525091;5135.87160320995;4898.412190537087;4932.432770231398;4936.851894443173;4937.185364414903;4938.43208031081;4217.093324935092;4228.112096459778;4230.70385073042;4239.682023848224;4240.957590424118;3710.9443358349645;3723.4600982857332;3723.664870161644;3723.7579557328686;3729.179580492287;4260.637845783143;4280.5291417944;4338.197433474817;4356.6732495063;4357.128574285156;5112.258722485219;5152.149895267314;5158.613104017214;5163.664361816545;5200.167973124282;4367.825286988548;4392.538783427758;4395.626371758436;4414.050979039522;4419.204543182258;4450.955490445119;4485.618722408632;4610.331501932201;4635.907281854344;4670.1126785910565;4911.140550403594;4916.950830491649;4932.4081110267;4944.159492886218;4980.427250866124;4970.8757281068265;5024.120778863611;5205.115130427912;5246.842594722164;5261.237120617566;4900.191468407673;4914.336564563738;4915.557599816034;4953.18272485036;4957.082939656175;4890.290907818276;4947.985662939017;4955.876103097451;5002.949970500248;5005;5017.37282839645;5064.5441910228255;5065.037642847848;5065.189569668835;5076.466970967996;4919.114559379203;4925.1627223372825;4926.2610608407595;4937.777799998937;4946.530079642428;4884.0476107158765;4913.1893237615395;4920.778564964514;4922.827701030446;4925.581325587222;4834.274858771212;4839.758012099452;4890.486498917562;4903.141950158779;4924.803007879645;5012.924806127838;5039.546440820343;5044.1424957756535;5049.2426136079885;5055.924161137659;4923.212286034217;4930.482299358516;4934.629902757236;4936.3089167515855;4952.579030782424 21:13:14 INFO - PROCESS | 23844 | |1;ext.html;536.3215532351576;537.376643094011;541.1990254423454;556.4574539823675;586.5923183387547;36425.81787091064;37118.432235164735;37178.8141059295;37182.62817371829;37202;38309.42535861964;39166.21667566647;39271.80364098182;39314;39336.409953850714;7615.695372185106;7788.6884524618945;7792.766217013499;7803.492772969739;9232.492212928286;9450.432974021547;9538.713838584854;9538.853757904451;9545.472724364103;9547.427154370725;9513.28650351223;9552.856707149398;9554.904450955506;9563.569639366246;9569.425834449921;9388.953055234748;9441.150296473315;9475.0998655128;9479.099485548863;9510.619575216982;9297.302702297287;9407.29445291606;9411.858822117674;9432.434053956784;9448.905510944871;32123.6787632123;32127.678723212935;32171;32219.19452013695;32447.51328730071;3334.299797042624;3345.96654033459;3390.7287417006582;3432.5880894292845;3433.8283085845856;24104.156354527506;24550.877245613836;24555;24558.631620525706;24651.137210197736;3099.318150006995;3160.2099475131217;3192.952105718416;3255.397751415972;3300.5544251525976;224808;226164;226834;227139;227224;30661.693383065885;31419.528707069414;42535.361969570484;44359.112817743466;44720.77639611781;42996.355054674204;45538;45596.316055259194;45889.770551147034;46225;11507.539698412138;11566.768664626828;11583.305001699924;11591.478383472595;11647.184697071123;9229.815403691888;9303;9303.53482325881;9307.301952353466;9329.673461428818;15355.769663455058;15357.539273821803;15360.155191464577;15447.768283475756;15559.922200388926;6146.692665366714;6363.96818015907;6432.324605916405;6530.575512591761;6562.114114594564;744.914334851499;749.4940914882433;760.2853317881174;767.7389687506163;775.7517594369748;25364.365890852878;26074.414777880564;26706;26712.19863404101;26736.465270694476;933.2254228989922;934.3226161033197;941.8493041113526;959.8080383923243;1005.3214080495783;13675.452981880613;13855.099418537762;13904.235267060409;14101.365438555289;14183.92908035474;307275;308850;309783;309806;310553;47408.762956185;47521;47606.28590571144;47629;47753.522464774906;1087.7878813631423;1131.7227279316621;1140.3556990300453;1142.360278244186;1153.0890596428894;24965.750342497067;25289.241322760347;25530.11279843762;25894.482110358065;26216.73783262143;40230;40564.79717601393;40702.38946415865;40732;40772.59227407688;265898;266282;268261;268595;269002;20611.175552977715;21345.893270533546;23332.883335583214;23562;24070.39824004414;38869.59550226334;39245.41131883081;39446.40830387604;39513;40066.79966600148;33456.33087338288;33624.663753362154;33649.66350336465;33760;34131.65868341384;32573.674263258014;32763.672363276062;33262.833685831414;34058.65941340555;34199.31601368009;32691.703683762516;33101.503477447375;33112.834435827666;33140.17149571184;33398.16504587307;27050.864745676932;29624.407511850073;29840.403191935606;30251.848740756155;30474.542881856345;22463.61632302899;22816.429589259737;22851.431712557693;22935.426614334774;22940 21:13:14 INFO - PROCESS | 23844 | |2;jquery.html;355.90746405934465;381.6107570278314;405.17951148923424;407.31164332278445;411.05047340643097;16597.668046639075;17764.644707105883;18274.269029238847;18405.723914141297;18574.442766717002;18255.178516966735;18407.815921840778;18480;18567.257309707595;18604.906975465103;5085.516875896793;5304.416514183436;5318.840434786963;5324.5740340772645;5331.120365139747;4970.179920313162;5182.637215394924;5192.948070519285;5271.499207575274;5276.7889284428575;5229.686218826863;5283.894322113555;5289.2859463972445;5292.497212764782;5303.893922121555;4654.208784506625;4674.649401294916;4677.9064418711605;4686.062787442525;4687.718736875764;4562.292844609057;4581.198290299184;4590.816367345302;4591.6556258280425;4599.172149013176;11702.824457633142;11758.470868810924;11858.703532411759;12047.156699031071;12081.577144799978;2128.6594144936735;2138.1233694185307;2211.3808133722423;2247.910083596654;2393.1623931623794;8716.956415217883;8988.370814043019;9098.727038188865;12034.592717165116;12646.241225526406;1661.5347702643157;1702.5232934778282;1732.5928406824387;1735.687576236277;1747.3831400336223;74360.62819685921;75882;75901.62049189719;76087.61956190184;78239.21760782377;10385;10819.996601257428;10827.67516974492;10959.780804384027;11176.441177941233;15182;15186.088834669956;15199.164045977357;15235.466758663393;15248.542543723706;6895.103636527319;7097.361237488651;7143.392811610967;7228.204897461275;7321.377682896907;6223.222097237845;6283.3088360280335;6395.936040639627;6403.903941440882;6503.479721622353;4584.19776539111;4615.3999980003055;4652;4795.760211989386;4809.134355815987;1902.581432084939;1935.187221367001;1971.5169783403157;1986.1459572383724;1995.1593183122432;931.7810314576069;957.1242313283407;964.8504481805331;973.6154219083422;975.5609975511048;16573.254203560868;17572.04628958474;17589.560260993578;17774.733378999324;17982.280708771763;930.5858892792643;935.733316004949;936.639393833379;959.4099628728421;962.138885697311;9462.57418416173;9617.855732164024;9908.35595686278;10016.699499015042;10484.580616775396;419904;440277;442287.78856105515;447985;448507;91927;93427;93750.5312473447;94637.52681236688;96418.51790741002;887.0995939121797;919.3702313914856;926.3654396738121;926.5274709898;932.1750251027754;15032.098074115587;15607.921960390126;16445;16585.50243492697;16702.916485417496;35636.821815890755;36135.63864361428;36547.81726091352;41326.38010429906;42234.218023691465;48305.5169448301;48968.75515622399;51068.40384365425;58084.419155807904;58145;7267.890981635385;7462.44031697614;7619.466637335333;7633.770986870402;7643.617819108911;8635.136486351315;11546;11914.046876249706;12298.631041068782;12442.191257568229;10356.637517686844;10422.270441069053;10454.529546170288;10484.895151048391;10759.462026898467;10342.5345859435;10466.267361284941;10558.260921735406;10636.89363106359;10691.625793097204;10322.948385258025;10590.205734569783;10825.077229147928;10893.673189804318;11001.724956876142;7580.848383032198;7656.846863062596;7684.65419056155;7779.805504862424;7800.570968596781;9632.711018669452;9934.950325248328;9957.253206009433;10284.485775711035;10686.519106640064 21:13:14 INFO - PROCESS | 23844 | |3;mootools.html;1353.587155917445;1369.1169195868665;1369.7808350663895;1380.233970146569;1382.529939820461;7480.738174163906;7632.9236707633045;7809.453338266324;7921.168277330884;7932.127465978745;7784;7899.64451599678;7908.3673306135515;7923.603819809001;7949.24482174194;2088.4465616611574;2124.2777455665073;2125.989370053155;2126;2128.1168315149193;2071.388940262626;2113.5944596843083;2115.3019503563864;2120.522882351474;2122.586095711337;2064.8244899183514;2070.87574745515;2091.968620470694;2097.9370618881458;2107.936761897138;2084.895755212241;2106.052276475592;2107.1466056247245;2111.915523379063;2116.492041909938;1732.9653406931916;1781.9198136083908;2022.9898850505801;2027.2397850805949;2088.1229883448987;7453.925460745378;7669.156392796788;7675.616219189074;7686.461947663665;7695.461317707763;4520.09598080382;4633.212353899845;4642.837500687459;4660.673752837302;4681.9297710534365;2838.446502931929;2838.758705510034;2901.2021694034097;2908.0548821633056;2942.661593916684;1068.0707784227693;1080.3949788118678;1108.8835672254459;1115.5147510832815;1133.648568943622;10438.84341734867;10455;10500.894991050069;10516.369017858877;10545.578176872992;3751.0997360633796;3895.3183192941688;3959.9010024749036;3962.346212874901;4025.476688030596;8263.752087437386;8269.710560130367;8321.66713331472;8338.541380224146;8341.124181960928;4391.121775644884;4409.977950110293;4416.53626369233;4416.823327066882;4440.578145076212;4111.732737372061;4118.423420721101;4244.087521182961;4270.338097594878;4295.52749197588;3366.495025746158;3366.73066154707;3467.2545402738533;3478.669526394988;3500.7374446916074;1056.1392465140887;1057.799018186542;1073.8872418396113;1082.1451053667672;1083.0631503749357;810.5501446697021;821.954792486407;823.8846561481397;828.0353388302681;835.7492752174323;4970.403551573762;5117.334746482933;5118.846434606968;5153.484651534816;5212.218167274863;469.6050144264225;509.1827616675183;513.6019584821719;513.6815174591777;844.472204871955;3249.431349513873;3399.4390925497014;3415.9316813663586;3443.810590417551;3508.122969257686;8038.437309388403;8383.790405239917;8845.42504737203;9136.132067453582;9156.130167634064;9301.395409298373;9351.625934962525;9360.204382627417;9407.106324899123;9463.668771593098;7013.2986701329455;7227.421806255487;7318.707251709979;7456.589887556236;7458.589777562178;4877.806625199205;5032.106538740166;5083.18669012971;5111.539961403491;5210.947890521046;8009.5995200238585;8349.582520874053;8549.829003419773;8590.570471476527;8635.395522313378;8449.577521124042;8734.29681212183;8804.515751633722;8805.867911981453;8961.417507861968;6460.160179176865;6653.301403352578;6655.134832471941;6663;6673.9332606673315;7127.10911136108;7445.851082978418;7448.590327532038;7557.130929943127;7616.04799400075;6506.41442270198;6649.202095748541;6672.132622759011;6683.348611351904;6891.138607674041;6339.587926784744;6476.514261430318;6507.544471886922;6543.411093001652;6596.670166491752;5478.096114141122;5758.078707406795;5838.211841401357;5848.122781582797;5855.238818953509;4430.933535996898;4488.102379524043;4630.513796051366;4664.300354946785;4692.601128904082;4481.731096134243;4562.452505699337;4664.207084795661;4716.622670186308;4824.517548245148 21:13:14 INFO - PROCESS | 23844 | |4;prototype.html;330.3920785754212;355.08211273857063;355.48632226432807;357.4870061461801;358.0190278636536;4890.535399137077;5048.242763585464;5079.679283386318;5107.6504807788415;5117.641765076446;4932.457429682732;4949.257611358298;4961.742407204722;4965.776540055697;5011.273365362021;3883.417487376902;3885.630865067814;3888.202918401735;3905.2384784966944;3909.4722212501374;3754.3242216401036;3864.4976153100197;3894.7078969077293;3918.745281556704;3925.744826586277;3822.7132965027736;3846.615338466159;3850.152966347399;3854.3447613905705;3864.3623802072625;3848.0956975110967;3849.9605106621334;3866.7679939203736;3867.323218436776;3873.3802591585495;3383.340248651514;3394.422948098804;3395.151212196951;3411.539442175324;3411.812350320731;4793.352897358882;4812.37439132914;4836.951630483721;4860.173770458995;4866.68832304656;4368.4102646143;4387.670924680661;4414.3378493225955;4418.3372494125815;4450.933236001462;5183.844484665466;5188.558972487343;5199.922001169985;5200.271961925334;5230.503102205284;3556.288742251534;3688.317661232654;3693.6675699186935;3709.406494960793;3720.534933133358;5222.451642577551;5306.336707911472;5358.383785864637;5485.6434331768305;5491.917621235684;3763.5107436033686;3773.4528493368857;3773.9245215095543;3777.9433308500393;3780.3384407728536;3805.879179073189;3832.80835958201;3835.520559930009;3840.807959602009;3852.4799152114106;4258.914821703549;4399.38408622793;4404.6256068233915;4415.6909016368545;4427.889302767392;5209.739513024334;5231.267622532849;5235.424103348629;5242.52817246442;5267.552258058031;1268.2770820632336;1275.2667216350635;1277.757226127033;1278.5972418688084;1283.7611704704818;821.34292565947;878.6265837019241;879.5953861223909;894.8926128864448;895.1987970766128;4325.9134817303475;4369.094552122866;4375.759333236702;4378.176076430685;4408.831659610206;5174.8873992091885;5198.6880787152895;5202.297689811827;5231.424543300234;5231.555317797954;4214.915701685949;4393.868183954486;4394.648428125743;4394.934075988863;4421.358902959053;2736.329599248197;2773.2512221700026;2778.8054836161273;2782.4852402305637;2798.328401183742;4890.633202509826;4896;4919.237518184687;4926.926096108561;4934.7285899275885;5134.332536770196;5313.1479677234465;5350.785968561214;5358.2498450217;5377.381601115843;4871.95128048715;4905.533974272438;4912.084287127951;4913;4938.308636790924;5048.621353398583;5216.478352164905;5242.47575242488;5248.527632513092;5254.3169387979315;4967.453580106113;5084.2545025687195;5136.9743151284;5154.561862241751;5216.139337009503;4814.855554333376;4837.93565415608;4938.283948827472;4949.183384741477;4980.427250866197;3711.461838033416;3711.536057992751;3719.75821571597;3732.3468393031653;3768.8492460302377;5256.028989129077;5281.725330295241;5294.861604754996;5311.309529761106;5327.413984461628;4977.328060711758;5155.860317968001;5181.870453238548;5201.531862132426;5249.580033597226;5226.268322434938;5229.424763276113;5233.188855727369;5234.842954711365;5258.8159414420315;5094.719790411563;5137.6660517066275;5140.820071297487;5142.76857541419;5156.4327923927585;5036.8992620146655;5142.1772516397195;5219.321488206509;5228.816991405282;5264.947350526599;5051.242313652834;5096.286519887293;5103.209002604603;5113.821016264413;5136.332276803992 21:13:14 INFO - PROCESS | 23844 | |5;yui.html;644.7752928436865;649.9187601549803;709.2269426325307;711.8256027273318;732.9926700732991;11816.645500634984;12594.244345339263;12599.055070869676;12652.936735316322;12654.620361389174;11287.322760634348;11306.660800175987;11307.869213078688;11341.37622430766;11359.545618175263;806.5684858600636;806.9435139540235;822.0258993093204;826.3224156191925;831.9209675080857;808.4462143431768;812.8333691593209;819.4059307002435;821.9465734727223;823.333100188846;860.7073594977707;861.1947828780079;862.5040601654042;865.4590880699562;866.6490071521044;851.8977722673256;861.6079683743882;861.9051904290523;862.1938487514194;862.8533149364591;621.5090078837712;623.5385814497284;623.7972658885835;625.0561651905655;627.2347735762409;13220.272884991322;13371.799423008662;13422.597322080353;13552.051356405056;13654.795178072336;12833.486660533568;13119.409626566732;13161;13274.402651880591;13280.136791108547;10080.17193140569;10275.588976441008;10307.433091179983;10494.475276236157;10569.682909512727;3134.45147099259;3170.302533442639;3186.9681303186903;3188.0595224408853;3249.2941205866873;55570.44429555693;56319.71840140773;56641.716791415776;57740.422595773925;57804.42195578032;9590.42457452555;9914.997325090604;9995.450250236307;10071.597136114473;10209.285350025428;16739.16304184786;16743.162841858102;16825.91587042057;16833.158342082847;16843.157842107845;6001.159837622737;6076.210092687922;6100.816975490742;6200.007998720183;6328;3376.7636265461674;3463.809490478048;3464.2898205867737;3476.42638964568;3477.6522347765526;2704.499667561468;2769.4737999779977;2772.306923269183;2791.776657867366;2803.831770093801;482.2284345047895;482.71278589239347;501.36827597227756;502.48244308362445;509.2158076562127;576.3141861185176;593.5577994394117;605.0289285696482;611.3153268339488;629.4303655192059;8171.7548473545885;8606.698765543177;8772.254358379481;8911.866322005175;8955.849129999026;476.80212711724243;501.7215445427823;512.344199424737;561.9547641387016;637.996810015947;4174.958250417518;4788.808447662124;4803.135435621622;4881.414230292387;4892.437369702458;62986;63796.36203637997;64598.677006614664;65012;66002;54345.1848222277;55736;56006.43993560012;56707.43292567104;57633.711831440574;40505.392419113734;48956.26565601519;50258.74870625696;50396.748016259684;51300.74349628302;9062.682806101755;9447.480388578695;9500.66747663856;9502.382345147544;9505.239580833379;29585.852070739507;29874.701252987194;30579.388412231187;33013.17467063342;34563.654363456044;42412.36381454219;42575.5742442584;43207.567924321615;43571.78214108909;43945;11082.168837337393;11240.325580465198;11300.49147788335;11478.598249061242;11520.292731207992;12683.30241836671;12767.170133941265;12803.807942880678;13026.218426894417;13037.543685970946;11860.40697965081;11878.881211187778;12022.158448908493;12457.875421245672;12504.937475312929;11468.139889508488;11888.108391870472;12163;12198.085143614442;12217.45021474018;12111.273323600612;12386;12403.62789116328;12619.936900315439;12717.364131793189;9896.802063958825;9966.651167209113;10012.918297952307;10166.847497287694;10213.591456341961;8838.55807209624;10401.167906567607;10556.419396932935;10704.785904282027;10939.617113400993 21:13:14 INFO - PROCESS | 23844 | __end_tp_report 21:13:14 INFO - PROCESS | 23844 | __start_cc_report 21:13:14 INFO - PROCESS | 23844 | _x_x_mozilla_cycle_collect,383 21:13:14 INFO - PROCESS | 23844 | __end_cc_report 21:13:14 INFO - PROCESS | 23844 | __startTimestamp1487653994317__endTimestamp 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | ------- Summary: start ------- 21:13:14 INFO - PROCESS | 23844 | Number of tests: 6 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | [#0] dojo.html Cycles:165 Average:4722.30 Median:4900.19 stddev:506.10 (10.3%) stddev-sans-first:479.52 21:13:14 INFO - PROCESS | 23844 | Values: 2601.2 2614.0 2624.9 2633.5 2650.7 4792.1 4803.4 4815.8 4827.0 4876.8 4816.7 4831.0 4834.2 4919.3 4954.2 4217.4 4436.3 4532.7 4556.4 4559.2 4507.6 4510.6 4516.0 4518.0 4520.2 4343.4 4384.2 4397.3 4443.8 4465.1 4402.2 4411.7 4433.3 4455.3 4459.3 4358.2 4375.8 4387.2 4394.3 4442.0 4612.3 4647.4 4649.9 4701.3 4856.4 5042.1 5053.8 5085.7 5085.8 5174.9 5136.0 5139.9 5140.2 5147.4 5152.9 4816.2 4821.7 4838.5 4856.3 4869.1 4999.9 5044.3 5300.4 5307.3 5341.7 5131.6 5131.9 5137.7 5141.9 5149.2 5165.5 5210.6 5226.3 5229.4 5251.4 4850.0 4876.6 4895.7 4952.5 5135.9 4898.4 4932.4 4936.9 4937.2 4938.4 4217.1 4228.1 4230.7 4239.7 4241.0 3710.9 3723.5 3723.7 3723.8 3729.2 4260.6 4280.5 4338.2 4356.7 4357.1 5112.3 5152.1 5158.6 5163.7 5200.2 4367.8 4392.5 4395.6 4414.1 4419.2 4451.0 4485.6 4610.3 4635.9 4670.1 4911.1 4917.0 4932.4 4944.2 4980.4 4970.9 5024.1 5205.1 5246.8 5261.2 4900.2 4914.3 4915.6 4953.2 4957.1 4890.3 4948.0 4955.9 5002.9 5005.0 5017.4 5064.5 5065.0 5065.2 5076.5 4919.1 4925.2 4926.3 4937.8 4946.5 4884.0 4913.2 4920.8 4922.8 4925.6 4834.3 4839.8 4890.5 4903.1 4924.8 5012.9 5039.5 5044.1 5049.2 5055.9 4923.2 4930.5 4934.6 4936.3 4952.6 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | [#1] ext.html Cycles:180 Average:41311.76 Median:33704.83 stddev:70595.63 (209.5%) stddev-sans-first:70727.28 21:13:14 INFO - PROCESS | 23844 | Values: 536.3 537.4 541.2 556.5 586.6 36425.8 37118.4 37178.8 37182.6 37202.0 38309.4 39166.2 39271.8 39314.0 39336.4 7615.7 7788.7 7792.8 7803.5 9232.5 9450.4 9538.7 9538.9 9545.5 9547.4 9513.3 9552.9 9554.9 9563.6 9569.4 9389.0 9441.2 9475.1 9479.1 9510.6 9297.3 9407.3 9411.9 9432.4 9448.9 32123.7 32127.7 32171.0 32219.2 32447.5 3334.3 3346.0 3390.7 3432.6 3433.8 24104.2 24550.9 24555.0 24558.6 24651.1 3099.3 3160.2 3193.0 3255.4 3300.6 224808.0 226164.0 226834.0 227139.0 227224.0 30661.7 31419.5 42535.4 44359.1 44720.8 42996.4 45538.0 45596.3 45889.8 46225.0 11507.5 11566.8 11583.3 11591.5 11647.2 9229.8 9303.0 9303.5 9307.3 9329.7 15355.8 15357.5 15360.2 15447.8 15559.9 6146.7 6364.0 6432.3 6530.6 6562.1 744.9 749.5 760.3 767.7 775.8 25364.4 26074.4 26706.0 26712.2 26736.5 933.2 934.3 941.8 959.8 1005.3 13675.5 13855.1 13904.2 14101.4 14183.9 307275.0 308850.0 309783.0 309806.0 310553.0 47408.8 47521.0 47606.3 47629.0 47753.5 1087.8 1131.7 1140.4 1142.4 1153.1 24965.8 25289.2 25530.1 25894.5 26216.7 40230.0 40564.8 40702.4 40732.0 40772.6 265898.0 266282.0 268261.0 268595.0 269002.0 20611.2 21345.9 23332.9 23562.0 24070.4 38869.6 39245.4 39446.4 39513.0 40066.8 33456.3 33624.7 33649.7 33760.0 34131.7 32573.7 32763.7 33262.8 34058.7 34199.3 32691.7 33101.5 33112.8 33140.2 33398.2 27050.9 29624.4 29840.4 30251.8 30474.5 22463.6 22816.4 22851.4 22935.4 22940.0 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | [#2] jquery.html Cycles:180 Average:26390.22 Median:4571.75 stddev:72899.60 (1594.6%) stddev-sans-first:73077.89 21:13:14 INFO - PROCESS | 23844 | Values: 355.9 381.6 405.2 407.3 411.1 16597.7 17764.6 18274.3 18405.7 18574.4 18255.2 18407.8 18480.0 18567.3 18604.9 5085.5 5304.4 5318.8 5324.6 5331.1 4970.2 5182.6 5192.9 5271.5 5276.8 5229.7 5283.9 5289.3 5292.5 5303.9 4654.2 4674.6 4677.9 4686.1 4687.7 4562.3 4581.2 4590.8 4591.7 4599.2 11702.8 11758.5 11858.7 12047.2 12081.6 2128.7 2138.1 2211.4 2247.9 2393.2 8717.0 8988.4 9098.7 12034.6 12646.2 1661.5 1702.5 1732.6 1735.7 1747.4 74360.6 75882.0 75901.6 76087.6 78239.2 10385.0 10820.0 10827.7 10959.8 11176.4 15182.0 15186.1 15199.2 15235.5 15248.5 6895.1 7097.4 7143.4 7228.2 7321.4 6223.2 6283.3 6395.9 6403.9 6503.5 4584.2 4615.4 4652.0 4795.8 4809.1 1902.6 1935.2 1971.5 1986.1 1995.2 931.8 957.1 964.9 973.6 975.6 16573.3 17572.0 17589.6 17774.7 17982.3 930.6 935.7 936.6 959.4 962.1 9462.6 9617.9 9908.4 10016.7 10484.6 419904.0 440277.0 442287.8 447985.0 448507.0 91927.0 93427.0 93750.5 94637.5 96418.5 887.1 919.4 926.4 926.5 932.2 15032.1 15607.9 16445.0 16585.5 16702.9 35636.8 36135.6 36547.8 41326.4 42234.2 48305.5 48968.8 51068.4 58084.4 58145.0 7267.9 7462.4 7619.5 7633.8 7643.6 8635.1 11546.0 11914.0 12298.6 12442.2 10356.6 10422.3 10454.5 10484.9 10759.5 10342.5 10466.3 10558.3 10636.9 10691.6 10322.9 10590.2 10825.1 10893.7 11001.7 7580.8 7656.8 7684.7 7779.8 7800.6 9632.7 9935.0 9957.3 10284.5 10686.5 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | [#3] mootools.html Cycles:180 Average:4978.44 Median:4851.16 stddev:2789.75 (57.5%) stddev-sans-first:2784.28 21:13:14 INFO - PROCESS | 23844 | Values: 1353.6 1369.1 1369.8 1380.2 1382.5 7480.7 7632.9 7809.5 7921.2 7932.1 7784.0 7899.6 7908.4 7923.6 7949.2 2088.4 2124.3 2126.0 2126.0 2128.1 2071.4 2113.6 2115.3 2120.5 2122.6 2064.8 2070.9 2092.0 2097.9 2107.9 2084.9 2106.1 2107.1 2111.9 2116.5 1733.0 1781.9 2023.0 2027.2 2088.1 7453.9 7669.2 7675.6 7686.5 7695.5 4520.1 4633.2 4642.8 4660.7 4681.9 2838.4 2838.8 2901.2 2908.1 2942.7 1068.1 1080.4 1108.9 1115.5 1133.6 10438.8 10455.0 10500.9 10516.4 10545.6 3751.1 3895.3 3959.9 3962.3 4025.5 8263.8 8269.7 8321.7 8338.5 8341.1 4391.1 4410.0 4416.5 4416.8 4440.6 4111.7 4118.4 4244.1 4270.3 4295.5 3366.5 3366.7 3467.3 3478.7 3500.7 1056.1 1057.8 1073.9 1082.1 1083.1 810.6 822.0 823.9 828.0 835.7 4970.4 5117.3 5118.8 5153.5 5212.2 469.6 509.2 513.6 513.7 844.5 3249.4 3399.4 3415.9 3443.8 3508.1 8038.4 8383.8 8845.4 9136.1 9156.1 9301.4 9351.6 9360.2 9407.1 9463.7 7013.3 7227.4 7318.7 7456.6 7458.6 4877.8 5032.1 5083.2 5111.5 5210.9 8009.6 8349.6 8549.8 8590.6 8635.4 8449.6 8734.3 8804.5 8805.9 8961.4 6460.2 6653.3 6655.1 6663.0 6673.9 7127.1 7445.9 7448.6 7557.1 7616.0 6506.4 6649.2 6672.1 6683.3 6891.1 6339.6 6476.5 6507.5 6543.4 6596.7 5478.1 5758.1 5838.2 5848.1 5855.2 4430.9 4488.1 4630.5 4664.3 4692.6 4481.7 4562.5 4664.2 4716.6 4824.5 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | [#4] prototype.html Cycles:180 Average:4277.59 Median:4900.77 stddev:1238.52 (25.3%) stddev-sans-first:1206.03 21:13:14 INFO - PROCESS | 23844 | Values: 330.4 355.1 355.5 357.5 358.0 4890.5 5048.2 5079.7 5107.7 5117.6 4932.5 4949.3 4961.7 4965.8 5011.3 3883.4 3885.6 3888.2 3905.2 3909.5 3754.3 3864.5 3894.7 3918.7 3925.7 3822.7 3846.6 3850.2 3854.3 3864.4 3848.1 3850.0 3866.8 3867.3 3873.4 3383.3 3394.4 3395.2 3411.5 3411.8 4793.4 4812.4 4837.0 4860.2 4866.7 4368.4 4387.7 4414.3 4418.3 4450.9 5183.8 5188.6 5199.9 5200.3 5230.5 3556.3 3688.3 3693.7 3709.4 3720.5 5222.5 5306.3 5358.4 5485.6 5491.9 3763.5 3773.5 3773.9 3777.9 3780.3 3805.9 3832.8 3835.5 3840.8 3852.5 4258.9 4399.4 4404.6 4415.7 4427.9 5209.7 5231.3 5235.4 5242.5 5267.6 1268.3 1275.3 1277.8 1278.6 1283.8 821.3 878.6 879.6 894.9 895.2 4325.9 4369.1 4375.8 4378.2 4408.8 5174.9 5198.7 5202.3 5231.4 5231.6 4214.9 4393.9 4394.6 4394.9 4421.4 2736.3 2773.3 2778.8 2782.5 2798.3 4890.6 4896.0 4919.2 4926.9 4934.7 5134.3 5313.1 5350.8 5358.2 5377.4 4872.0 4905.5 4912.1 4913.0 4938.3 5048.6 5216.5 5242.5 5248.5 5254.3 4967.5 5084.3 5137.0 5154.6 5216.1 4814.9 4837.9 4938.3 4949.2 4980.4 3711.5 3711.5 3719.8 3732.3 3768.8 5256.0 5281.7 5294.9 5311.3 5327.4 4977.3 5155.9 5181.9 5201.5 5249.6 5226.3 5229.4 5233.2 5234.8 5258.8 5094.7 5137.7 5140.8 5142.8 5156.4 5036.9 5142.2 5219.3 5228.8 5264.9 5051.2 5096.3 5103.2 5113.8 5136.3 21:13:14 INFO - PROCESS | 23844 | 21:13:14 INFO - PROCESS | 23844 | [#5] yui.html Cycles:180 Average:14265.28 Median:22210.90 stddev:17324.79 (78.0%) stddev-sans-first:17343.19 21:13:14 INFO - PROCESS | 23844 | Values: 644.8 649.9 709.2 711.8 733.0 11816.6 12594.2 12599.1 12652.9 12654.6 11287.3 11306.7 11307.9 11341.4 11359.5 806.6 806.9 822.0 826.3 831.9 808.4 812.8 819.4 821.9 823.3 860.7 861.2 862.5 865.5 866.6 851.9 861.6 861.9 862.2 862.9 621.5 623.5 623.8 625.1 627.2 13220.3 13371.8 13422.6 13552.1 13654.8 12833.5 13119.4 13161.0 13274.4 13280.1 10080.2 10275.6 10307.4 10494.5 10569.7 3134.5 3170.3 3187.0 3188.1 3249.3 55570.4 56319.7 56641.7 57740.4 57804.4 9590.4 9915.0 9995.5 10071.6 10209.3 16739.2 16743.2 16825.9 16833.2 16843.2 6001.2 6076.2 6100.8 6200.0 6328.0 3376.8 3463.8 3464.3 3476.4 3477.7 2704.5 2769.5 2772.3 2791.8 2803.8 482.2 482.7 501.4 502.5 509.2 576.3 593.6 605.0 611.3 629.4 8171.8 8606.7 8772.3 8911.9 8955.8 476.8 501.7 512.3 562.0 638.0 4175.0 4788.8 4803.1 4881.4 4892.4 62986.0 63796.4 64598.7 65012.0 66002.0 54345.2 55736.0 56006.4 56707.4 57633.7 40505.4 48956.3 50258.7 50396.7 51300.7 9062.7 9447.5 9500.7 9502.4 9505.2 29585.9 29874.7 30579.4 33013.2 34563.7 42412.4 42575.6 43207.6 43571.8 43945.0 11082.2 11240.3 11300.5 11478.6 11520.3 12683.3 12767.2 12803.8 13026.2 13037.5 11860.4 11878.9 12022.2 12457.9 12504.9 11468.1 11888.1 12163.0 12198.1 12217.5 12111.3 12386.0 12403.6 12619.9 12717.4 9896.8 9966.7 10012.9 10166.8 10213.6 8838.6 10401.2 10556.4 10704.8 10939.6 21:13:14 INFO - PROCESS | 23844 | -------- Summary: end -------- 21:13:14 INFO - PROCESS | 23844 | 21:13:15 INFO - TEST-INFO | 23844: exit 0 21:13:15 INFO - TEST-OK | dromaeo_css | took 1120700ms 21:13:15 INFO - TEST-START | kraken 21:13:15 INFO - operating with platform_type : linux_ 21:13:15 INFO - Initialising browser for kraken test... 21:13:15 INFO - TEST-INFO | started process 24223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWkXpEn/profile http://localhost:47145/getInfo.html) 21:13:18 INFO - PROCESS | 24223 | Iterating over profile /tmp/tmpWkXpEn/profile 21:13:18 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:18 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/places.sqlite-shm = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/crashes = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/minidumps = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/extensions.json = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/sessionCheckpoints.json = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/key3.db = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/permissions.sqlite = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/user.js = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/.parentlock = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | __metrics Screen width/height:1600/1200 21:13:19 INFO - PROCESS | 24223 | colorDepth:24 21:13:19 INFO - PROCESS | 24223 | Browser inner width/height: 1024/697 21:13:19 INFO - PROCESS | 24223 | __metrics 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/cert8.db = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/compatibility.ini = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/places.sqlite = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/startupCache = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/pluginreg.dat = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/directoryLinks.json = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/prefs.js = Mon Feb 20 2017 20:53:54 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/thumbnails = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/addons.json = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/places.sqlite-wal = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | Stat failure 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/safebrowsing = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/secmod.db = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/extensions.ini = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/cache2 = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/localstore.rdf = Mon Feb 20 2017 20:53:54 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/content-prefs.sqlite = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/frequencyCap.json = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/gmp = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/extensions = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/bookmarkbackups = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:19 INFO - PROCESS | 24223 | No birth date. Using mtime. 21:13:19 INFO - PROCESS | 24223 | Using date: /tmp/tmpWkXpEn/profile/blocklist.xml = Mon Feb 20 2017 21:13:15 GMT-0800 (PST) 21:13:20 ERROR - PROCESS | 24223 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 21:13:21 INFO - TEST-INFO | 24223: exit 0 21:13:21 INFO - Browser initialized. 21:13:21 INFO - Running cycle 1/1 for kraken test... 21:13:21 INFO - TEST-INFO | started process 24355 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWkXpEn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:13:33 INFO - PROCESS | 24355 | RSS: Main: 157982720 21:13:33 INFO - PROCESS | 24355 | 21:13:36 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-beat-detection) 21:13:37 INFO - PROCESS | 24355 | RSS: Main: 162308096 21:13:37 INFO - PROCESS | 24355 | 21:13:40 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-dft) 21:13:40 INFO - PROCESS | 24355 | RSS: Main: 161116160 21:13:40 INFO - PROCESS | 24355 | 21:13:43 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-fft) 21:13:44 INFO - PROCESS | 24355 | RSS: Main: 155787264 21:13:44 INFO - PROCESS | 24355 | 21:13:46 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-oscillator) 21:13:47 INFO - PROCESS | 24355 | RSS: Main: 156463104 21:13:47 INFO - PROCESS | 24355 | 21:13:49 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47145/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:13:49 INFO - PROCESS | 24355 | RSS: Main: 157151232 21:13:49 INFO - PROCESS | 24355 | 21:13:53 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47145/tests/kraken/driver.html?testName=imaging-darkroom) 21:13:53 INFO - PROCESS | 24355 | RSS: Main: 162226176 21:13:53 INFO - PROCESS | 24355 | 21:13:57 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47145/tests/kraken/driver.html?testName=imaging-desaturate) 21:13:57 INFO - PROCESS | 24355 | RSS: Main: 158625792 21:13:57 INFO - PROCESS | 24355 | 21:14:00 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47145/tests/kraken/driver.html?testName=json-parse-financial) 21:14:01 INFO - PROCESS | 24355 | RSS: Main: 159875072 21:14:01 INFO - PROCESS | 24355 | 21:14:03 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47145/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:14:03 INFO - PROCESS | 24355 | RSS: Main: 159166464 21:14:03 INFO - PROCESS | 24355 | 21:14:05 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:14:06 INFO - PROCESS | 24355 | RSS: Main: 158928896 21:14:06 INFO - PROCESS | 24355 | 21:14:08 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:14:09 INFO - PROCESS | 24355 | RSS: Main: 159498240 21:14:09 INFO - PROCESS | 24355 | 21:14:12 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:14:12 INFO - PROCESS | 24355 | RSS: Main: 160006144 21:14:12 INFO - PROCESS | 24355 | 21:14:16 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:14:16 INFO - PROCESS | 24355 | RSS: Main: 163037184 21:14:16 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47145/tests/kraken/driver.html?testName=ai-astar) 21:14:18 INFO - PROCESS | 24355 | RSS: Main: 164204544 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | __start_tp_report 21:14:18 INFO - PROCESS | 24355 | _x_x_mozilla_page_load 21:14:18 INFO - PROCESS | 24355 | _x_x_mozilla_page_load_details 21:14:18 INFO - PROCESS | 24355 | |i|pagename|runs| 21:14:18 INFO - PROCESS | 24355 | |0;ai-astar;110;116;120;118;119;120;121;120;119;121 21:14:18 INFO - PROCESS | 24355 | |1;audio-beat-detection;161;127;126;169;134;130;129;127;160;132 21:14:18 INFO - PROCESS | 24355 | |2;audio-dft;164;167;170;168;168;162;162;165;164;167 21:14:18 INFO - PROCESS | 24355 | |3;audio-fft;77;76;77;107;84;78;76;78;100;77 21:14:18 INFO - PROCESS | 24355 | |4;audio-oscillator;84;84;121;85;84;84;98;84;85;105 21:14:18 INFO - PROCESS | 24355 | |5;imaging-gaussian-blur;114;115;115;115;119;113;115;115;114;114 21:14:18 INFO - PROCESS | 24355 | |6;imaging-darkroom;149;152;150;151;150;152;150;152;152;150 21:14:18 INFO - PROCESS | 24355 | |7;imaging-desaturate;97;97;96;97;99;96;96;97;97;96 21:14:18 INFO - PROCESS | 24355 | |8;json-parse-financial;67;69;67;69;67;67;67;69;68;68 21:14:18 INFO - PROCESS | 24355 | |9;json-stringify-tinderbox;66;66;66;66;67;69;66;64;66;66 21:14:18 INFO - PROCESS | 24355 | |10;stanford-crypto-aes;89;95;88;90;88;96;87;90;89;87 21:14:18 INFO - PROCESS | 24355 | |11;stanford-crypto-ccm;143;140;142;147;150;144;140;151;195;139 21:14:18 INFO - PROCESS | 24355 | |12;stanford-crypto-pbkdf2;191;188;184;189;190;189;185;187;190;197 21:14:18 INFO - PROCESS | 24355 | |13;stanford-crypto-sha256-iterative;71;71;71;71;72;73;75;75;71;81 21:14:18 INFO - PROCESS | 24355 | __end_tp_report 21:14:18 INFO - PROCESS | 24355 | __start_cc_report 21:14:18 INFO - PROCESS | 24355 | _x_x_mozilla_cycle_collect,881 21:14:18 INFO - PROCESS | 24355 | __end_cc_report 21:14:18 INFO - PROCESS | 24355 | __startTimestamp1487654058722__endTimestamp 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | ------- Summary: start ------- 21:14:18 INFO - PROCESS | 24355 | Number of tests: 14 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#0] ai-astar Cycles:10 Average:118.40 Median:120.00 stddev:3.31 (2.8%) stddev-sans-first:1.58 21:14:18 INFO - PROCESS | 24355 | Values: 110.0 116.0 120.0 118.0 119.0 120.0 121.0 120.0 119.0 121.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:133.00 stddev:16.78 (12.6%) stddev-sans-first:15.89 21:14:18 INFO - PROCESS | 24355 | Values: 161.0 127.0 126.0 169.0 134.0 130.0 129.0 127.0 160.0 132.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#2] audio-dft Cycles:10 Average:165.70 Median:167.00 stddev:2.71 (1.6%) stddev-sans-first:2.80 21:14:18 INFO - PROCESS | 24355 | Values: 164.0 167.0 170.0 168.0 168.0 162.0 162.0 165.0 164.0 167.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#3] audio-fft Cycles:10 Average:83.00 Median:77.00 stddev:11.17 (14.5%) stddev-sans-first:11.63 21:14:18 INFO - PROCESS | 24355 | Values: 77.0 76.0 77.0 107.0 84.0 78.0 76.0 78.0 100.0 77.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:84.00 stddev:12.74 (15.2%) stddev-sans-first:13.23 21:14:18 INFO - PROCESS | 24355 | Values: 84.0 84.0 121.0 85.0 84.0 84.0 98.0 84.0 85.0 105.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:1.60 (1.4%) stddev-sans-first:1.66 21:14:18 INFO - PROCESS | 24355 | Values: 114.0 115.0 115.0 115.0 119.0 113.0 115.0 115.0 114.0 114.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.50 stddev:1.14 (0.7%) stddev-sans-first:1.00 21:14:18 INFO - PROCESS | 24355 | Values: 149.0 152.0 150.0 151.0 150.0 152.0 150.0 152.0 152.0 150.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:97.00 stddev:0.92 (0.9%) stddev-sans-first:0.97 21:14:18 INFO - PROCESS | 24355 | Values: 97.0 97.0 96.0 97.0 99.0 96.0 96.0 97.0 97.0 96.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 21:14:18 INFO - PROCESS | 24355 | Values: 67.0 69.0 67.0 69.0 67.0 67.0 67.0 69.0 68.0 68.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.30 21:14:18 INFO - PROCESS | 24355 | Values: 66.0 66.0 66.0 66.0 67.0 69.0 66.0 64.0 66.0 66.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:89.50 stddev:3.14 (3.5%) stddev-sans-first:3.32 21:14:18 INFO - PROCESS | 24355 | Values: 89.0 95.0 88.0 90.0 88.0 96.0 87.0 90.0 89.0 87.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:145.50 stddev:16.66 (11.4%) stddev-sans-first:17.52 21:14:18 INFO - PROCESS | 24355 | Values: 143.0 140.0 142.0 147.0 150.0 144.0 140.0 151.0 195.0 139.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:189.50 stddev:3.59 (1.9%) stddev-sans-first:3.73 21:14:18 INFO - PROCESS | 24355 | Values: 191.0 188.0 184.0 189.0 190.0 189.0 185.0 187.0 190.0 197.0 21:14:18 INFO - PROCESS | 24355 | 21:14:18 INFO - PROCESS | 24355 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:72.50 stddev:3.21 (4.4%) stddev-sans-first:3.32 21:14:18 INFO - PROCESS | 24355 | Values: 71.0 71.0 71.0 71.0 72.0 73.0 75.0 75.0 71.0 81.0 21:14:18 INFO - PROCESS | 24355 | -------- Summary: end -------- 21:14:18 INFO - PROCESS | 24355 | 21:14:20 INFO - TEST-INFO | 24355: exit 0 21:14:20 INFO - TEST-OK | kraken | took 64882ms 21:14:20 INFO - SUITE-END | took 1185s 21:14:20 INFO - Completed test suite (00:19:45) 21:14:20 INFO - javascript benchmark 21:14:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6290.209305865241, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2601.1546247469573, 2614.045873256262, 2624.8556329401886, 2633.5259653262406, 2650.695170055443, 4792.089502994429, 4803.351547541081, 4815.783289751961, 4827.0, 4876.756162191885, 4816.69949113739, 4830.951690483094, 4834.178189707749, 4919.286703428001, 4954.232094025432, 4217.448870222277, 4436.334549817521, 4532.682712210147, 4556.430446194226, 4559.224931761609, 4507.639388848885, 4510.571495707903, 4515.954840451603, 4518.028623845872, 4520.2315606347, 4343.435353404069, 4384.16700826845, 4397.252467080604, 4443.777811109431, 4465.084657645175, 4402.185595664812, 4411.669124815651, 4433.290673492225, 4455.287154055368, 4459.264221403465, 4358.193734159159, 4375.781210939472, 4387.210302145612, 4394.340848872663, 4441.956140307026, 4612.331211974279, 4647.37260469839, 4649.930251046237, 4701.341812146302, 4856.39295088114, 5042.1176294148745, 5053.8231161909525, 5085.74571271435, 5085.771140298695, 5174.896502069938, 5135.974320128413, 5139.948600513984, 5140.228965655144, 5147.4080480744815, 5152.9484705152845, 4816.181249187646, 4821.662483626183, 4838.516148385203, 4856.295837103636, 4869.050535145612, 4999.875003124878, 5044.344235249394, 5300.416954135042, 5307.310049693515, 5341.65279256847, 5131.589472842164, 5131.87170320745, 5137.717425541557, 5141.948580514147, 5149.2276158576515, 5165.457626949192, 5210.557102646318, 5226.294450249244, 5229.398619158846, 5251.422343542207, 4849.975750121227, 4876.609871210295, 4895.73073480962, 4952.504749525091, 5135.87160320995, 4898.412190537087, 4932.432770231398, 4936.851894443173, 4937.185364414903, 4938.43208031081, 4217.093324935092, 4228.112096459778, 4230.70385073042, 4239.682023848224, 4240.957590424118, 3710.9443358349645, 3723.4600982857332, 3723.664870161644, 3723.7579557328686, 3729.179580492287, 4260.637845783143, 4280.5291417944, 4338.197433474817, 4356.6732495063, 4357.128574285156, 5112.258722485219, 5152.149895267314, 5158.613104017214, 5163.664361816545, 5200.167973124282, 4367.825286988548, 4392.538783427758, 4395.626371758436, 4414.050979039522, 4419.204543182258, 4450.955490445119, 4485.618722408632, 4610.331501932201, 4635.907281854344, 4670.1126785910565, 4911.140550403594, 4916.950830491649, 4932.4081110267, 4944.159492886218, 4980.427250866124, 4970.8757281068265, 5024.120778863611, 5205.115130427912, 5246.842594722164, 5261.237120617566, 4900.191468407673, 4914.336564563738, 4915.557599816034, 4953.18272485036, 4957.082939656175, 4890.290907818276, 4947.985662939017, 4955.876103097451, 5002.949970500248, 5005.0, 5017.37282839645, 5064.5441910228255, 5065.037642847848, 5065.189569668835, 5076.466970967996, 4919.114559379203, 4925.1627223372825, 4926.2610608407595, 4937.777799998937, 4946.530079642428, 4884.0476107158765, 4913.1893237615395, 4920.778564964514, 4922.827701030446, 4925.581325587222, 4834.274858771212, 4839.758012099452, 4890.486498917562, 4903.141950158779, 4924.803007879645, 5012.924806127838, 5039.546440820343, 5044.1424957756535, 5049.2426136079885, 5055.924161137659, 4923.212286034217, 4930.482299358516, 4934.629902757236, 4936.3089167515855, 4952.579030782424], "value": 4689.0306508818, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.3215532351576, 537.376643094011, 541.1990254423454, 556.4574539823675, 586.5923183387547, 36425.81787091064, 37118.432235164735, 37178.8141059295, 37182.62817371829, 37202.0, 38309.42535861964, 39166.21667566647, 39271.80364098182, 39314.0, 39336.409953850714, 7615.695372185106, 7788.6884524618945, 7792.766217013499, 7803.492772969739, 9232.492212928286, 9450.432974021547, 9538.713838584854, 9538.853757904451, 9545.472724364103, 9547.427154370725, 9513.28650351223, 9552.856707149398, 9554.904450955506, 9563.569639366246, 9569.425834449921, 9388.953055234748, 9441.150296473315, 9475.0998655128, 9479.099485548863, 9510.619575216982, 9297.302702297287, 9407.29445291606, 9411.858822117674, 9432.434053956784, 9448.905510944871, 32123.6787632123, 32127.678723212935, 32171.0, 32219.19452013695, 32447.51328730071, 3334.299797042624, 3345.96654033459, 3390.7287417006582, 3432.5880894292845, 3433.8283085845856, 24104.156354527506, 24550.877245613836, 24555.0, 24558.631620525706, 24651.137210197736, 3099.318150006995, 3160.2099475131217, 3192.952105718416, 3255.397751415972, 3300.5544251525976, 224808.0, 226164.0, 226834.0, 227139.0, 227224.0, 30661.693383065885, 31419.528707069414, 42535.361969570484, 44359.112817743466, 44720.77639611781, 42996.355054674204, 45538.0, 45596.316055259194, 45889.770551147034, 46225.0, 11507.539698412138, 11566.768664626828, 11583.305001699924, 11591.478383472595, 11647.184697071123, 9229.815403691888, 9303.0, 9303.53482325881, 9307.301952353466, 9329.673461428818, 15355.769663455058, 15357.539273821803, 15360.155191464577, 15447.768283475756, 15559.922200388926, 6146.692665366714, 6363.96818015907, 6432.324605916405, 6530.575512591761, 6562.114114594564, 744.914334851499, 749.4940914882433, 760.2853317881174, 767.7389687506163, 775.7517594369748, 25364.365890852878, 26074.414777880564, 26706.0, 26712.19863404101, 26736.465270694476, 933.2254228989922, 934.3226161033197, 941.8493041113526, 959.8080383923243, 1005.3214080495783, 13675.452981880613, 13855.099418537762, 13904.235267060409, 14101.365438555289, 14183.92908035474, 307275.0, 308850.0, 309783.0, 309806.0, 310553.0, 47408.762956185, 47521.0, 47606.28590571144, 47629.0, 47753.522464774906, 1087.7878813631423, 1131.7227279316621, 1140.3556990300453, 1142.360278244186, 1153.0890596428894, 24965.750342497067, 25289.241322760347, 25530.11279843762, 25894.482110358065, 26216.73783262143, 40230.0, 40564.79717601393, 40702.38946415865, 40732.0, 40772.59227407688, 265898.0, 266282.0, 268261.0, 268595.0, 269002.0, 20611.175552977715, 21345.893270533546, 23332.883335583214, 23562.0, 24070.39824004414, 38869.59550226334, 39245.41131883081, 39446.40830387604, 39513.0, 40066.79966600148, 33456.33087338288, 33624.663753362154, 33649.66350336465, 33760.0, 34131.65868341384, 32573.674263258014, 32763.672363276062, 33262.833685831414, 34058.65941340555, 34199.31601368009, 32691.703683762516, 33101.503477447375, 33112.834435827666, 33140.17149571184, 33398.16504587307, 27050.864745676932, 29624.407511850073, 29840.403191935606, 30251.848740756155, 30474.542881856345, 22463.61632302899, 22816.429589259737, 22851.431712557693, 22935.426614334774, 22940.0], "value": 16377.774758372982, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.90746405934465, 381.6107570278314, 405.17951148923424, 407.31164332278445, 411.05047340643097, 16597.668046639075, 17764.644707105883, 18274.269029238847, 18405.723914141297, 18574.442766717002, 18255.178516966735, 18407.815921840778, 18480.0, 18567.257309707595, 18604.906975465103, 5085.516875896793, 5304.416514183436, 5318.840434786963, 5324.5740340772645, 5331.120365139747, 4970.179920313162, 5182.637215394924, 5192.948070519285, 5271.499207575274, 5276.7889284428575, 5229.686218826863, 5283.894322113555, 5289.2859463972445, 5292.497212764782, 5303.893922121555, 4654.208784506625, 4674.649401294916, 4677.9064418711605, 4686.062787442525, 4687.718736875764, 4562.292844609057, 4581.198290299184, 4590.816367345302, 4591.6556258280425, 4599.172149013176, 11702.824457633142, 11758.470868810924, 11858.703532411759, 12047.156699031071, 12081.577144799978, 2128.6594144936735, 2138.1233694185307, 2211.3808133722423, 2247.910083596654, 2393.1623931623794, 8716.956415217883, 8988.370814043019, 9098.727038188865, 12034.592717165116, 12646.241225526406, 1661.5347702643157, 1702.5232934778282, 1732.5928406824387, 1735.687576236277, 1747.3831400336223, 74360.62819685921, 75882.0, 75901.62049189719, 76087.61956190184, 78239.21760782377, 10385.0, 10819.996601257428, 10827.67516974492, 10959.780804384027, 11176.441177941233, 15182.0, 15186.088834669956, 15199.164045977357, 15235.466758663393, 15248.542543723706, 6895.103636527319, 7097.361237488651, 7143.392811610967, 7228.204897461275, 7321.377682896907, 6223.222097237845, 6283.3088360280335, 6395.936040639627, 6403.903941440882, 6503.479721622353, 4584.19776539111, 4615.3999980003055, 4652.0, 4795.760211989386, 4809.134355815987, 1902.581432084939, 1935.187221367001, 1971.5169783403157, 1986.1459572383724, 1995.1593183122432, 931.7810314576069, 957.1242313283407, 964.8504481805331, 973.6154219083422, 975.5609975511048, 16573.254203560868, 17572.04628958474, 17589.560260993578, 17774.733378999324, 17982.280708771763, 930.5858892792643, 935.733316004949, 936.639393833379, 959.4099628728421, 962.138885697311, 9462.57418416173, 9617.855732164024, 9908.35595686278, 10016.699499015042, 10484.580616775396, 419904.0, 440277.0, 442287.78856105515, 447985.0, 448507.0, 91927.0, 93427.0, 93750.5312473447, 94637.52681236688, 96418.51790741002, 887.0995939121797, 919.3702313914856, 926.3654396738121, 926.5274709898, 932.1750251027754, 15032.098074115587, 15607.921960390126, 16445.0, 16585.50243492697, 16702.916485417496, 35636.821815890755, 36135.63864361428, 36547.81726091352, 41326.38010429906, 42234.218023691465, 48305.5169448301, 48968.75515622399, 51068.40384365425, 58084.419155807904, 58145.0, 7267.890981635385, 7462.44031697614, 7619.466637335333, 7633.770986870402, 7643.617819108911, 8635.136486351315, 11546.0, 11914.046876249706, 12298.631041068782, 12442.191257568229, 10356.637517686844, 10422.270441069053, 10454.529546170288, 10484.895151048391, 10759.462026898467, 10342.5345859435, 10466.267361284941, 10558.260921735406, 10636.89363106359, 10691.625793097204, 10322.948385258025, 10590.205734569783, 10825.077229147928, 10893.673189804318, 11001.724956876142, 7580.848383032198, 7656.846863062596, 7684.65419056155, 7779.805504862424, 7800.570968596781, 9632.711018669452, 9934.950325248328, 9957.253206009433, 10284.485775711035, 10686.519106640064], "value": 8350.14660916774, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.587155917445, 1369.1169195868665, 1369.7808350663895, 1380.233970146569, 1382.529939820461, 7480.738174163906, 7632.9236707633045, 7809.453338266324, 7921.168277330884, 7932.127465978745, 7784.0, 7899.64451599678, 7908.3673306135515, 7923.603819809001, 7949.24482174194, 2088.4465616611574, 2124.2777455665073, 2125.989370053155, 2126.0, 2128.1168315149193, 2071.388940262626, 2113.5944596843083, 2115.3019503563864, 2120.522882351474, 2122.586095711337, 2064.8244899183514, 2070.87574745515, 2091.968620470694, 2097.9370618881458, 2107.936761897138, 2084.895755212241, 2106.052276475592, 2107.1466056247245, 2111.915523379063, 2116.492041909938, 1732.9653406931916, 1781.9198136083908, 2022.9898850505801, 2027.2397850805949, 2088.1229883448987, 7453.925460745378, 7669.156392796788, 7675.616219189074, 7686.461947663665, 7695.461317707763, 4520.09598080382, 4633.212353899845, 4642.837500687459, 4660.673752837302, 4681.9297710534365, 2838.446502931929, 2838.758705510034, 2901.2021694034097, 2908.0548821633056, 2942.661593916684, 1068.0707784227693, 1080.3949788118678, 1108.8835672254459, 1115.5147510832815, 1133.648568943622, 10438.84341734867, 10455.0, 10500.894991050069, 10516.369017858877, 10545.578176872992, 3751.0997360633796, 3895.3183192941688, 3959.9010024749036, 3962.346212874901, 4025.476688030596, 8263.752087437386, 8269.710560130367, 8321.66713331472, 8338.541380224146, 8341.124181960928, 4391.121775644884, 4409.977950110293, 4416.53626369233, 4416.823327066882, 4440.578145076212, 4111.732737372061, 4118.423420721101, 4244.087521182961, 4270.338097594878, 4295.52749197588, 3366.495025746158, 3366.73066154707, 3467.2545402738533, 3478.669526394988, 3500.7374446916074, 1056.1392465140887, 1057.799018186542, 1073.8872418396113, 1082.1451053667672, 1083.0631503749357, 810.5501446697021, 821.954792486407, 823.8846561481397, 828.0353388302681, 835.7492752174323, 4970.403551573762, 5117.334746482933, 5118.846434606968, 5153.484651534816, 5212.218167274863, 469.6050144264225, 509.1827616675183, 513.6019584821719, 513.6815174591777, 844.472204871955, 3249.431349513873, 3399.4390925497014, 3415.9316813663586, 3443.810590417551, 3508.122969257686, 8038.437309388403, 8383.790405239917, 8845.42504737203, 9136.132067453582, 9156.130167634064, 9301.395409298373, 9351.625934962525, 9360.204382627417, 9407.106324899123, 9463.668771593098, 7013.2986701329455, 7227.421806255487, 7318.707251709979, 7456.589887556236, 7458.589777562178, 4877.806625199205, 5032.106538740166, 5083.18669012971, 5111.539961403491, 5210.947890521046, 8009.5995200238585, 8349.582520874053, 8549.829003419773, 8590.570471476527, 8635.395522313378, 8449.577521124042, 8734.29681212183, 8804.515751633722, 8805.867911981453, 8961.417507861968, 6460.160179176865, 6653.301403352578, 6655.134832471941, 6663.0, 6673.9332606673315, 7127.10911136108, 7445.851082978418, 7448.590327532038, 7557.130929943127, 7616.04799400075, 6506.41442270198, 6649.202095748541, 6672.132622759011, 6683.348611351904, 6891.138607674041, 6339.587926784744, 6476.514261430318, 6507.544471886922, 6543.411093001652, 6596.670166491752, 5478.096114141122, 5758.078707406795, 5838.211841401357, 5848.122781582797, 5855.238818953509, 4430.933535996898, 4488.102379524043, 4630.513796051366, 4664.300354946785, 4692.601128904082, 4481.731096134243, 4562.452505699337, 4664.207084795661, 4716.622670186308, 4824.517548245148], "value": 3981.239512855733, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.3920785754212, 355.08211273857063, 355.48632226432807, 357.4870061461801, 358.0190278636536, 4890.535399137077, 5048.242763585464, 5079.679283386318, 5107.6504807788415, 5117.641765076446, 4932.457429682732, 4949.257611358298, 4961.742407204722, 4965.776540055697, 5011.273365362021, 3883.417487376902, 3885.630865067814, 3888.202918401735, 3905.2384784966944, 3909.4722212501374, 3754.3242216401036, 3864.4976153100197, 3894.7078969077293, 3918.745281556704, 3925.744826586277, 3822.7132965027736, 3846.615338466159, 3850.152966347399, 3854.3447613905705, 3864.3623802072625, 3848.0956975110967, 3849.9605106621334, 3866.7679939203736, 3867.323218436776, 3873.3802591585495, 3383.340248651514, 3394.422948098804, 3395.151212196951, 3411.539442175324, 3411.812350320731, 4793.352897358882, 4812.37439132914, 4836.951630483721, 4860.173770458995, 4866.68832304656, 4368.4102646143, 4387.670924680661, 4414.3378493225955, 4418.3372494125815, 4450.933236001462, 5183.844484665466, 5188.558972487343, 5199.922001169985, 5200.271961925334, 5230.503102205284, 3556.288742251534, 3688.317661232654, 3693.6675699186935, 3709.406494960793, 3720.534933133358, 5222.451642577551, 5306.336707911472, 5358.383785864637, 5485.6434331768305, 5491.917621235684, 3763.5107436033686, 3773.4528493368857, 3773.9245215095543, 3777.9433308500393, 3780.3384407728536, 3805.879179073189, 3832.80835958201, 3835.520559930009, 3840.807959602009, 3852.4799152114106, 4258.914821703549, 4399.38408622793, 4404.6256068233915, 4415.6909016368545, 4427.889302767392, 5209.739513024334, 5231.267622532849, 5235.424103348629, 5242.52817246442, 5267.552258058031, 1268.2770820632336, 1275.2667216350635, 1277.757226127033, 1278.5972418688084, 1283.7611704704818, 821.34292565947, 878.6265837019241, 879.5953861223909, 894.8926128864448, 895.1987970766128, 4325.9134817303475, 4369.094552122866, 4375.759333236702, 4378.176076430685, 4408.831659610206, 5174.8873992091885, 5198.6880787152895, 5202.297689811827, 5231.424543300234, 5231.555317797954, 4214.915701685949, 4393.868183954486, 4394.648428125743, 4394.934075988863, 4421.358902959053, 2736.329599248197, 2773.2512221700026, 2778.8054836161273, 2782.4852402305637, 2798.328401183742, 4890.633202509826, 4896.0, 4919.237518184687, 4926.926096108561, 4934.7285899275885, 5134.332536770196, 5313.1479677234465, 5350.785968561214, 5358.2498450217, 5377.381601115843, 4871.95128048715, 4905.533974272438, 4912.084287127951, 4913.0, 4938.308636790924, 5048.621353398583, 5216.478352164905, 5242.47575242488, 5248.527632513092, 5254.3169387979315, 4967.453580106113, 5084.2545025687195, 5136.9743151284, 5154.561862241751, 5216.139337009503, 4814.855554333376, 4837.93565415608, 4938.283948827472, 4949.183384741477, 4980.427250866197, 3711.461838033416, 3711.536057992751, 3719.75821571597, 3732.3468393031653, 3768.8492460302377, 5256.028989129077, 5281.725330295241, 5294.861604754996, 5311.309529761106, 5327.413984461628, 4977.328060711758, 5155.860317968001, 5181.870453238548, 5201.531862132426, 5249.580033597226, 5226.268322434938, 5229.424763276113, 5233.188855727369, 5234.842954711365, 5258.8159414420315, 5094.719790411563, 5137.6660517066275, 5140.820071297487, 5142.76857541419, 5156.4327923927585, 5036.8992620146655, 5142.1772516397195, 5219.321488206509, 5228.816991405282, 5264.947350526599, 5051.242313652834, 5096.286519887293, 5103.209002604603, 5113.821016264413, 5136.332276803992], "value": 3894.8405562689177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.7752928436865, 649.9187601549803, 709.2269426325307, 711.8256027273318, 732.9926700732991, 11816.645500634984, 12594.244345339263, 12599.055070869676, 12652.936735316322, 12654.620361389174, 11287.322760634348, 11306.660800175987, 11307.869213078688, 11341.37622430766, 11359.545618175263, 806.5684858600636, 806.9435139540235, 822.0258993093204, 826.3224156191925, 831.9209675080857, 808.4462143431768, 812.8333691593209, 819.4059307002435, 821.9465734727223, 823.333100188846, 860.7073594977707, 861.1947828780079, 862.5040601654042, 865.4590880699562, 866.6490071521044, 851.8977722673256, 861.6079683743882, 861.9051904290523, 862.1938487514194, 862.8533149364591, 621.5090078837712, 623.5385814497284, 623.7972658885835, 625.0561651905655, 627.2347735762409, 13220.272884991322, 13371.799423008662, 13422.597322080353, 13552.051356405056, 13654.795178072336, 12833.486660533568, 13119.409626566732, 13161.0, 13274.402651880591, 13280.136791108547, 10080.17193140569, 10275.588976441008, 10307.433091179983, 10494.475276236157, 10569.682909512727, 3134.45147099259, 3170.302533442639, 3186.9681303186903, 3188.0595224408853, 3249.2941205866873, 55570.44429555693, 56319.71840140773, 56641.716791415776, 57740.422595773925, 57804.42195578032, 9590.42457452555, 9914.997325090604, 9995.450250236307, 10071.597136114473, 10209.285350025428, 16739.16304184786, 16743.162841858102, 16825.91587042057, 16833.158342082847, 16843.157842107845, 6001.159837622737, 6076.210092687922, 6100.816975490742, 6200.007998720183, 6328.0, 3376.7636265461674, 3463.809490478048, 3464.2898205867737, 3476.42638964568, 3477.6522347765526, 2704.499667561468, 2769.4737999779977, 2772.306923269183, 2791.776657867366, 2803.831770093801, 482.2284345047895, 482.71278589239347, 501.36827597227756, 502.48244308362445, 509.2158076562127, 576.3141861185176, 593.5577994394117, 605.0289285696482, 611.3153268339488, 629.4303655192059, 8171.7548473545885, 8606.698765543177, 8772.254358379481, 8911.866322005175, 8955.849129999026, 476.80212711724243, 501.7215445427823, 512.344199424737, 561.9547641387016, 637.996810015947, 4174.958250417518, 4788.808447662124, 4803.135435621622, 4881.414230292387, 4892.437369702458, 62986.0, 63796.36203637997, 64598.677006614664, 65012.0, 66002.0, 54345.1848222277, 55736.0, 56006.43993560012, 56707.43292567104, 57633.711831440574, 40505.392419113734, 48956.26565601519, 50258.74870625696, 50396.748016259684, 51300.74349628302, 9062.682806101755, 9447.480388578695, 9500.66747663856, 9502.382345147544, 9505.239580833379, 29585.852070739507, 29874.701252987194, 30579.388412231187, 33013.17467063342, 34563.654363456044, 42412.36381454219, 42575.5742442584, 43207.567924321615, 43571.78214108909, 43945.0, 11082.168837337393, 11240.325580465198, 11300.49147788335, 11478.598249061242, 11520.292731207992, 12683.30241836671, 12767.170133941265, 12803.807942880678, 13026.218426894417, 13037.543685970946, 11860.40697965081, 11878.881211187778, 12022.158448908493, 12457.875421245672, 12504.937475312929, 11468.139889508488, 11888.108391870472, 12163.0, 12198.085143614442, 12217.45021474018, 12111.273323600612, 12386.0, 12403.62789116328, 12619.936900315439, 12717.364131793189, 9896.802063958825, 9966.651167209113, 10012.918297952307, 10166.847497287694, 10213.591456341961, 8838.55807209624, 10401.167906567607, 10556.419396932935, 10704.785904282027, 10939.617113400993], "value": 6228.784935206106, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 120.0, 118.0, 119.0, 120.0, 121.0, 120.0, 119.0, 121.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 127.0, 126.0, 169.0, 134.0, 130.0, 129.0, 127.0, 160.0, 132.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 170.0, 168.0, 168.0, 162.0, 162.0, 165.0, 164.0, 167.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 107.0, 84.0, 78.0, 76.0, 78.0, 100.0, 77.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 121.0, 85.0, 84.0, 84.0, 98.0, 84.0, 85.0, 105.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 115.0, 119.0, 113.0, 115.0, 115.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 150.0, 151.0, 150.0, 152.0, 150.0, 152.0, 152.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 99.0, 96.0, 96.0, 97.0, 97.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 69.0, 67.0, 67.0, 67.0, 69.0, 68.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 67.0, 69.0, 66.0, 64.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 88.0, 90.0, 88.0, 96.0, 87.0, 90.0, 89.0, 87.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 142.0, 147.0, 150.0, 144.0, 140.0, 151.0, 195.0, 139.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 188.0, 184.0, 189.0, 190.0, 189.0, 185.0, 187.0, 190.0, 197.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 71.0, 71.0, 72.0, 73.0, 75.0, 75.0, 71.0, 81.0], "value": 73.1, "unit": "score"}]}]} 21:14:20 INFO - Return code: 0 21:14:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:14:20 ERROR - # TBPL SUCCESS # 21:14:20 INFO - Running post-action listener: _resource_record_post_action 21:14:20 INFO - [mozharness: 2017-02-21 05:14:20.515453Z] Finished run-tests step (success) 21:14:20 INFO - Running post-run listener: _resource_record_post_run 21:14:20 INFO - Total resource usage - Wall time: 1201s; CPU: 14.0%; Read bytes: 12288; Write bytes: 360984576; Read time: 244; Write time: 229076 21:14:20 INFO - TinderboxPrint: CPU usage
13.5% 21:14:20 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 244 21:14:20 INFO - TinderboxPrint: I/O write bytes / time
360,984,576 / 229,076 21:14:20 INFO - TinderboxPrint: CPU idle
8,287.8 (86.5%) 21:14:20 INFO - TinderboxPrint: CPU user
1,239.0 (12.9%) 21:14:20 INFO - TinderboxPrint: Swap in / out
0 / 0 21:14:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105480192; Read time: 48; Write time: 112768 21:14:20 INFO - run-tests - Wall time: 1186s; CPU: 14.0%; Read bytes: 8192; Write bytes: 255504384; Read time: 196; Write time: 116308 21:14:21 INFO - Running post-run listener: _upload_blobber_files 21:14:21 INFO - Blob upload gear active. 21:14:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:14:21 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 21:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:14:21 INFO - (blobuploader) - INFO - Open directory for files ... 21:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:22 INFO - (blobuploader) - INFO - Done attempting. 21:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:25 INFO - (blobuploader) - INFO - Done attempting. 21:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:26 INFO - (blobuploader) - INFO - Done attempting. 21:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:26 INFO - (blobuploader) - INFO - Done attempting. 21:14:26 INFO - (blobuploader) - INFO - Iteration through files over. 21:14:26 INFO - Return code: 0 21:14:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:14:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:14:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5f7f7a7d6421133fd7d42123df10b31cd412713075f6e2eb873a63abed754eeadcacf4a3de0a2a7efc44f4a2f7db669aa05bff9f8d8170a1a371f7954c1750f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed99dfcf0aebeb2e9030ebf64fb06eec081808cc524c7dcec02a22445453bc2dd4b3a88602ce93a9bec32d109fa34bc229e275ddc0fe3cb8424153d814c0d2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c881fbd7296173532ab6027fe73f646a90f086947eff01f2f6d4b6ad02e119be3f2a442520e5ef0b30fe4cde42fbe51a2428133a4defaa60289288e7744b8738", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5d504581804cc5caf7e2140210b928e4586aab70081346c8b986ac74c5cb1fbcf3ce3c13cd4977d145028962baed0d98ad81a718334affea0f42d6b54ddabe0"} 21:14:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:14:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:14:26 INFO - Contents: 21:14:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5f7f7a7d6421133fd7d42123df10b31cd412713075f6e2eb873a63abed754eeadcacf4a3de0a2a7efc44f4a2f7db669aa05bff9f8d8170a1a371f7954c1750f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed99dfcf0aebeb2e9030ebf64fb06eec081808cc524c7dcec02a22445453bc2dd4b3a88602ce93a9bec32d109fa34bc229e275ddc0fe3cb8424153d814c0d2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c881fbd7296173532ab6027fe73f646a90f086947eff01f2f6d4b6ad02e119be3f2a442520e5ef0b30fe4cde42fbe51a2428133a4defaa60289288e7744b8738", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5d504581804cc5caf7e2140210b928e4586aab70081346c8b986ac74c5cb1fbcf3ce3c13cd4977d145028962baed0d98ad81a718334affea0f42d6b54ddabe0"} 21:14:26 INFO - Running post-run listener: copy_logs_to_upload_dir 21:14:26 INFO - Copying logs to upload dir... 21:14:26 INFO - mkdir: /builds/slave/test/build/upload/logs 21:14:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.981964 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-20 21:14:26.568759) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:26.572699) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5f7f7a7d6421133fd7d42123df10b31cd412713075f6e2eb873a63abed754eeadcacf4a3de0a2a7efc44f4a2f7db669aa05bff9f8d8170a1a371f7954c1750f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed99dfcf0aebeb2e9030ebf64fb06eec081808cc524c7dcec02a22445453bc2dd4b3a88602ce93a9bec32d109fa34bc229e275ddc0fe3cb8424153d814c0d2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c881fbd7296173532ab6027fe73f646a90f086947eff01f2f6d4b6ad02e119be3f2a442520e5ef0b30fe4cde42fbe51a2428133a4defaa60289288e7744b8738", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5d504581804cc5caf7e2140210b928e4586aab70081346c8b986ac74c5cb1fbcf3ce3c13cd4977d145028962baed0d98ad81a718334affea0f42d6b54ddabe0"} build_url:https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011288 build_url: 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5f7f7a7d6421133fd7d42123df10b31cd412713075f6e2eb873a63abed754eeadcacf4a3de0a2a7efc44f4a2f7db669aa05bff9f8d8170a1a371f7954c1750f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed99dfcf0aebeb2e9030ebf64fb06eec081808cc524c7dcec02a22445453bc2dd4b3a88602ce93a9bec32d109fa34bc229e275ddc0fe3cb8424153d814c0d2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c881fbd7296173532ab6027fe73f646a90f086947eff01f2f6d4b6ad02e119be3f2a442520e5ef0b30fe4cde42fbe51a2428133a4defaa60289288e7744b8738", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5d504581804cc5caf7e2140210b928e4586aab70081346c8b986ac74c5cb1fbcf3ce3c13cd4977d145028962baed0d98ad81a718334affea0f42d6b54ddabe0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:26.597323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:26.597619) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487652469.445002-531256447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:26.684494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:14:26.684997) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:14:26.685646) ========= ========= Total master_lag: 0.15 =========