builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1490139535.56 results: success (0) revision: 8744e9f8eb99f1290aae81985812d57364f18708 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.564245) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.564663) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.564947) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.579033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.579288) ========= 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=52faae308a556c440401f8e5000001e8-1490139535.259856-988796537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-21 16:38:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2017-03-21 16:38:56 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155376 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.748195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.748522) ========= 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=52faae308a556c440401f8e5000001e8-1490139535.259856-988796537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015125 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.797537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:55.797860) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8744e9f8eb99f1290aae81985812d57364f18708 --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 8744e9f8eb99f1290aae81985812d57364f18708 --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=52faae308a556c440401f8e5000001e8-1490139535.259856-988796537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-21 16:38:56,121 truncating revision to first 12 chars 2017-03-21 16:38:56,121 Setting DEBUG logging. 2017-03-21 16:38:56,121 attempt 1/10 2017-03-21 16:38:56,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8744e9f8eb99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 16:38:56,723 unpacking tar archive at: mozilla-central-8744e9f8eb99/testing/mozharness/ program finished with exit code 0 elapsedTime=0.892374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:56.707971) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:56.708826) ========= script_repo_revision: 8744e9f8eb99f1290aae81985812d57364f18708 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:56.709750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:56.710518) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 16:38:56.722342) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-21 16:38:56.722650) ========= /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=52faae308a556c440401f8e5000001e8-1490139535.259856-988796537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:38:57 INFO - MultiFileLogger online at 20170321 16:38:57 in /builds/slave/test 16:38:57 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 16:38:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:38:57 INFO - {'append_to_log': False, 16:38:57 INFO - 'base_work_dir': '/builds/slave/test', 16:38:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:38:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:38:57 INFO - 'branch': 'Firefox-Non-PGO', 16:38:57 INFO - 'buildbot_json_path': 'buildprops.json', 16:38:57 INFO - 'config_files': ('talos/linux_config.py',), 16:38:57 INFO - 'default_actions': ('clobber', 16:38:57 INFO - 'read-buildbot-config', 16:38:57 INFO - 'download-and-extract', 16:38:57 INFO - 'populate-webroot', 16:38:57 INFO - 'create-virtualenv', 16:38:57 INFO - 'install', 16:38:57 INFO - 'run-tests'), 16:38:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:38:57 INFO - 'download_minidump_stackwalk': True, 16:38:57 INFO - 'download_symbols': 'ondemand', 16:38:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:38:57 INFO - 'tooltool.py': '/tools/tooltool.py', 16:38:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:38:57 INFO - '/tools/misc-python/virtualenv.py')}, 16:38:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:38:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:38:57 INFO - 'gecko_profile': False, 16:38:57 INFO - 'gecko_profile_interval': 0, 16:38:57 INFO - 'installer_path': 'installer.exe', 16:38:57 INFO - 'log_level': 'info', 16:38:57 INFO - 'log_name': 'talos', 16:38:57 INFO - 'log_to_console': True, 16:38:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:38:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:38:57 INFO - 'opt_config_files': (), 16:38:57 INFO - 'pip_index': False, 16:38:57 INFO - 'suite': 'dromaeojs-e10s', 16:38:57 INFO - 'system_bits': '32', 16:38:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:38:57 INFO - 'title': 'talos-linux64-ix-056', 16:38:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:38:57 INFO - 'use_talos_json': True, 16:38:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:38:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:38:57 INFO - 'work_dir': 'build'} 16:38:57 INFO - [mozharness: 2017-03-21 23:38:57.146909Z] Running clobber step. 16:38:57 INFO - Running pre-action listener: _resource_record_pre_action 16:38:57 INFO - Running main action method: clobber 16:38:57 INFO - rmtree: /builds/slave/test/build 16:38:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:38:58 INFO - Running post-action listener: _resource_record_post_action 16:38:58 INFO - [mozharness: 2017-03-21 23:38:58.084907Z] Finished clobber step (success) 16:38:58 INFO - [mozharness: 2017-03-21 23:38:58.085007Z] Running read-buildbot-config step. 16:38:58 INFO - Running pre-action listener: _resource_record_pre_action 16:38:58 INFO - Running main action method: read_buildbot_config 16:38:58 INFO - Using buildbot properties: 16:38:58 INFO - { 16:38:58 INFO - "project": "", 16:38:58 INFO - "product": "firefox", 16:38:58 INFO - "who": "kwierso@gmail.com", 16:38:58 INFO - "installer_path": "public/build/target.tar.bz2", 16:38:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:38:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:38:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:38:58 INFO - "stage_platform": "linux64", 16:38:58 INFO - "basedir": "/builds/slave/test", 16:38:58 INFO - "buildnumber": 765, 16:38:58 INFO - "platform": "ubuntu64_hw", 16:38:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:38:58 INFO - "slavebuilddir": "test", 16:38:58 INFO - "taskId": "ZBtAuVIDTFKOvIsAiae6-A", 16:38:58 INFO - "branch": "mozilla-central", 16:38:58 INFO - "script_repo_revision": "production", 16:38:58 INFO - "revision": "8744e9f8eb99f1290aae81985812d57364f18708", 16:38:58 INFO - "slavename": "talos-linux64-ix-056", 16:38:58 INFO - "repo_path": "mozilla-central" 16:38:58 INFO - } 16:38:58 INFO - Finding installer, test and symbols from parent task. 16:38:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZBtAuVIDTFKOvIsAiae6-A'}, attempt #1 16:38:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZBtAuVIDTFKOvIsAiae6-A'}, attempt #1 16:38:59 INFO - Task dependencies: HuOhXf_MT0-mU7I9TNQtaQ 16:38:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ'}, attempt #1 16:38:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2 16:38:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/test_packages.json 16:38:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.crashreporter-symbols.zip 16:38:59 INFO - Running post-action listener: _resource_record_post_action 16:38:59 INFO - [mozharness: 2017-03-21 23:38:59.528177Z] Finished read-buildbot-config step (success) 16:38:59 INFO - [mozharness: 2017-03-21 23:38:59.528395Z] Running download-and-extract step. 16:38:59 INFO - Running pre-action listener: _resource_record_pre_action 16:38:59 INFO - Running main action method: download_and_extract 16:38:59 INFO - mkdir: /builds/slave/test/build/tests 16:38:59 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')]} 16:38:59 INFO - trying https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.test_packages.json 16:38:59 INFO - Downloading https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:38:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:39:00 INFO - Downloaded 986 bytes. 16:39:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:39:00 INFO - Using the following test package requirements: 16:39:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:39:00 INFO - u'common': [u'target.common.tests.zip'], 16:39:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:39:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:39:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:39:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:39:00 INFO - u'mozbase': [u'target.common.tests.zip'], 16:39:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:39:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:39:00 INFO - u'web-platform': [u'target.common.tests.zip', 16:39:00 INFO - u'target.web-platform.tests.zip'], 16:39:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:39:00 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:39:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.common.tests.zip 16:39:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:39:00 INFO - Fetch https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.common.tests.zip into memory 16:39:02 INFO - Expected file size: 58193451 16:39:02 INFO - Obtained file size: 58193451 16:39:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:39:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.common.tests.zip 16:39:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:39:11 INFO - Fetch https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.common.tests.zip into memory 16:39:13 INFO - Expected file size: 58193451 16:39:13 INFO - Obtained file size: 58193451 16:39:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.talos.tests.zip 16:39:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:39:23 INFO - Fetch https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.talos.tests.zip into memory 16:39:24 INFO - Expected file size: 14404171 16:39:24 INFO - Obtained file size: 14404171 16:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:24 INFO - trying https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2 16:39:24 INFO - Downloading https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:39:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:39:25 INFO - Downloaded 61893342 bytes. 16:39:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2 16:39:25 INFO - mkdir: /builds/slave/test/properties 16:39:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:39:25 INFO - Writing to file /builds/slave/test/properties/build_url 16:39:25 INFO - Contents: 16:39:25 INFO - build_url:https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2 16:39:25 INFO - Running post-action listener: _resource_record_post_action 16:39:25 INFO - Running post-action listener: set_extra_try_arguments 16:39:25 INFO - [mozharness: 2017-03-21 23:39:25.847488Z] Finished download-and-extract step (success) 16:39:25 INFO - [mozharness: 2017-03-21 23:39:25.847585Z] Running populate-webroot step. 16:39:25 INFO - Running pre-action listener: _resource_record_pre_action 16:39:25 INFO - Running main action method: populate_webroot 16:39:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:39:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:39:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:39:25 INFO - '--tppagecycles', 16:39:25 INFO - '7'], 16:39:25 INFO - 'tests': ['tsvgm']}}, 16:39:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:39:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:39:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:39:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:39:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:39:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:39:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['damp', 'tps']}, 16:39:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'tests': ['damp', 'tps']}, 16:39:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['dromaeo_dom']}, 16:39:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:39:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['basic_compositor_video']}, 16:39:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:39:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['a11yr', 16:39:25 INFO - 'ts_paint', 16:39:25 INFO - 'tpaint', 16:39:25 INFO - 'sessionrestore', 16:39:25 INFO - 'sessionrestore_no_auto_restore', 16:39:25 INFO - 'tabpaint']}, 16:39:25 INFO - 'other-e10s': {'tests': ['a11yr', 16:39:25 INFO - 'ts_paint', 16:39:25 INFO - 'tpaint', 16:39:25 INFO - 'sessionrestore', 16:39:25 INFO - 'sessionrestore_no_auto_restore', 16:39:25 INFO - 'tabpaint']}, 16:39:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:39:25 INFO - 'ts_paint', 16:39:25 INFO - 'tpaint', 16:39:25 INFO - 'sessionrestore', 16:39:25 INFO - 'sessionrestore_no_auto_restore', 16:39:25 INFO - 'tabpaint']}, 16:39:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:39:25 INFO - 'ts_paint', 16:39:25 INFO - 'tpaint', 16:39:25 INFO - 'sessionrestore', 16:39:25 INFO - 'sessionrestore_no_auto_restore', 16:39:25 INFO - 'tabpaint']}, 16:39:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['a11yr', 16:39:25 INFO - 'ts_paint', 16:39:25 INFO - 'tpaint', 16:39:25 INFO - 'sessionrestore', 16:39:25 INFO - 'sessionrestore_no_auto_restore', 16:39:25 INFO - 'tabpaint']}, 16:39:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['a11yr', 16:39:25 INFO - 'ts_paint', 16:39:25 INFO - 'tpaint', 16:39:25 INFO - 'sessionrestore', 16:39:25 INFO - 'sessionrestore_no_auto_restore', 16:39:25 INFO - 'tabpaint']}, 16:39:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['tsvgx', 16:39:25 INFO - 'tsvgr_opacity', 16:39:25 INFO - 'tart', 16:39:25 INFO - 'tscrollx', 16:39:25 INFO - 'cart', 16:39:25 INFO - 'tsvg_static']}, 16:39:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:39:25 INFO - 'tsvgr_opacity', 16:39:25 INFO - 'tart', 16:39:25 INFO - 'tscrollx', 16:39:25 INFO - 'cart', 16:39:25 INFO - 'tsvg_static']}, 16:39:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'talos_options': ['--disable-e10s'], 16:39:25 INFO - 'tests': ['tp5o']}, 16:39:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:39:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'talos_options': ['--disable-e10s', 16:39:25 INFO - '--xperf_path', 16:39:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:39:25 INFO - 'tests': ['tp5n']}, 16:39:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:39:25 INFO - 'talos_options': ['--xperf_path', 16:39:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:39:25 INFO - 'tests': ['tp5n']}}, 16:39:25 INFO - 'talos.zip': {'path': '', 16:39:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:39:25 INFO - Running post-action listener: _resource_record_post_action 16:39:25 INFO - [mozharness: 2017-03-21 23:39:25.860793Z] Finished populate-webroot step (success) 16:39:25 INFO - [mozharness: 2017-03-21 23:39:25.860883Z] Running create-virtualenv step. 16:39:25 INFO - Running pre-action listener: _resource_record_pre_action 16:39:25 INFO - Running main action method: create_virtualenv 16:39:25 INFO - Creating virtualenv /builds/slave/test/build/venv 16:39:25 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 16:39:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:39:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:39:25 INFO - Using real prefix '/usr' 16:39:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:39:26 INFO - Installing distribute.............................................................................................................................................................................................done. 16:39:28 INFO - Installing pip.................done. 16:39:28 INFO - Return code: 0 16:39:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:39:28 INFO - Reading from file tmpfile_stdout 16:39:28 INFO - Output received: 16:39:28 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) 16:39:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:28 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:28 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 16:39:28 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 16:39:28 INFO - Using env: {'DISPLAY': ':0', 16:39:28 INFO - 'HOME': '/home/cltbld', 16:39:28 INFO - 'LANG': 'en_US.UTF-8', 16:39:28 INFO - 'LANGUAGE': 'en_US:en', 16:39:28 INFO - 'LOGNAME': 'cltbld', 16:39:28 INFO - 'MAIL': '/var/mail/cltbld', 16:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:28 INFO - 'MOZ_NO_REMOTE': '1', 16:39:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:28 INFO - 'NO_EM_RESTART': '1', 16:39:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:28 INFO - 'PWD': '/builds/slave/test', 16:39:28 INFO - 'SHELL': '/bin/bash', 16:39:28 INFO - 'SHLVL': '1', 16:39:28 INFO - 'TERM': 'linux', 16:39:28 INFO - 'TMOUT': '86400', 16:39:28 INFO - 'USER': 'cltbld', 16:39:28 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:28 INFO - '_': '/tools/buildbot/bin/python'} 16:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:28 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 16:39:28 INFO - Cleaning up... 16:39:28 INFO - Return code: 0 16:39:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:28 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:28 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 16:39:28 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 16:39:28 INFO - Using env: {'DISPLAY': ':0', 16:39:28 INFO - 'HOME': '/home/cltbld', 16:39:28 INFO - 'LANG': 'en_US.UTF-8', 16:39:28 INFO - 'LANGUAGE': 'en_US:en', 16:39:28 INFO - 'LOGNAME': 'cltbld', 16:39:28 INFO - 'MAIL': '/var/mail/cltbld', 16:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:28 INFO - 'MOZ_NO_REMOTE': '1', 16:39:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:28 INFO - 'NO_EM_RESTART': '1', 16:39:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:28 INFO - 'PWD': '/builds/slave/test', 16:39:28 INFO - 'SHELL': '/bin/bash', 16:39:28 INFO - 'SHLVL': '1', 16:39:28 INFO - 'TERM': 'linux', 16:39:28 INFO - 'TMOUT': '86400', 16:39:28 INFO - 'USER': 'cltbld', 16:39:28 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:28 INFO - '_': '/tools/buildbot/bin/python'} 16:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:29 INFO - Downloading/unpacking psutil>=3.1.1 16:39:29 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 16:39:29 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 16:39:29 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 16:39:29 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 16:39:29 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 16:39:29 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 16:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:39:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:39:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:39:31 INFO - Installing collected packages: psutil 16:39:31 INFO - Running setup.py install for psutil 16:39:31 INFO - building 'psutil._psutil_linux' extension 16:39:31 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 16:39:31 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 16:39:31 INFO - building 'psutil._psutil_posix' extension 16:39:31 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 16:39:31 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 16:39:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:39:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:39:31 INFO - Successfully installed psutil 16:39:31 INFO - Cleaning up... 16:39:31 INFO - Return code: 0 16:39:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:39:31 INFO - Using env: {'DISPLAY': ':0', 16:39:31 INFO - 'HOME': '/home/cltbld', 16:39:31 INFO - 'LANG': 'en_US.UTF-8', 16:39:31 INFO - 'LANGUAGE': 'en_US:en', 16:39:31 INFO - 'LOGNAME': 'cltbld', 16:39:31 INFO - 'MAIL': '/var/mail/cltbld', 16:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:31 INFO - 'MOZ_NO_REMOTE': '1', 16:39:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:31 INFO - 'NO_EM_RESTART': '1', 16:39:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:31 INFO - 'PWD': '/builds/slave/test', 16:39:31 INFO - 'SHELL': '/bin/bash', 16:39:31 INFO - 'SHLVL': '1', 16:39:31 INFO - 'TERM': 'linux', 16:39:31 INFO - 'TMOUT': '86400', 16:39:31 INFO - 'USER': 'cltbld', 16:39:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:31 INFO - '_': '/tools/buildbot/bin/python'} 16:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:39:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:39:34 INFO - Installing collected packages: mozsystemmonitor 16:39:34 INFO - Running setup.py install for mozsystemmonitor 16:39:34 INFO - Successfully installed mozsystemmonitor 16:39:34 INFO - Cleaning up... 16:39:34 INFO - Return code: 0 16:39:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:39: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')]} 16:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39: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')]} 16:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:39:34 INFO - Using env: {'DISPLAY': ':0', 16:39:34 INFO - 'HOME': '/home/cltbld', 16:39:34 INFO - 'LANG': 'en_US.UTF-8', 16:39:34 INFO - 'LANGUAGE': 'en_US:en', 16:39:34 INFO - 'LOGNAME': 'cltbld', 16:39:34 INFO - 'MAIL': '/var/mail/cltbld', 16:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:34 INFO - 'MOZ_NO_REMOTE': '1', 16:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:34 INFO - 'NO_EM_RESTART': '1', 16:39:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:34 INFO - 'PWD': '/builds/slave/test', 16:39:34 INFO - 'SHELL': '/bin/bash', 16:39:34 INFO - 'SHLVL': '1', 16:39:34 INFO - 'TERM': 'linux', 16:39:34 INFO - 'TMOUT': '86400', 16:39:34 INFO - 'USER': 'cltbld', 16:39:34 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:34 INFO - '_': '/tools/buildbot/bin/python'} 16:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:34 INFO - Downloading/unpacking jsonschema==2.5.1 16:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:39:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:39:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:39:36 INFO - warning: no files found matching '*.txt' 16:39:36 INFO - no previously-included directories found matching 'build' 16:39:36 INFO - no previously-included directories found matching 'dist' 16:39:36 INFO - no previously-included directories found matching '.git*' 16:39:36 INFO - Installing collected packages: jsonschema, functools32 16:39:36 INFO - Running setup.py install for functools32 16:39:36 INFO - warning: no files found matching '*.txt' 16:39:36 INFO - no previously-included directories found matching 'build' 16:39:36 INFO - no previously-included directories found matching 'dist' 16:39:36 INFO - no previously-included directories found matching '.git*' 16:39:36 INFO - Successfully installed jsonschema functools32 16:39:36 INFO - Cleaning up... 16:39:36 INFO - Return code: 0 16:39:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:39:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:39:36 INFO - Using env: {'DISPLAY': ':0', 16:39:36 INFO - 'HOME': '/home/cltbld', 16:39:36 INFO - 'LANG': 'en_US.UTF-8', 16:39:36 INFO - 'LANGUAGE': 'en_US:en', 16:39:36 INFO - 'LOGNAME': 'cltbld', 16:39:36 INFO - 'MAIL': '/var/mail/cltbld', 16:39:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:36 INFO - 'MOZ_NO_REMOTE': '1', 16:39:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:36 INFO - 'NO_EM_RESTART': '1', 16:39:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:36 INFO - 'PWD': '/builds/slave/test', 16:39:36 INFO - 'SHELL': '/bin/bash', 16:39:36 INFO - 'SHLVL': '1', 16:39:36 INFO - 'TERM': 'linux', 16:39:36 INFO - 'TMOUT': '86400', 16:39:36 INFO - 'USER': 'cltbld', 16:39:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:36 INFO - '_': '/tools/buildbot/bin/python'} 16:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:39:37 INFO - Cleaning up... 16:39:37 INFO - Return code: 0 16:39:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:39:37 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')]} 16:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:37 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')]} 16:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:37 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:37 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 16:39:37 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 16:39:37 INFO - Using env: {'DISPLAY': ':0', 16:39:37 INFO - 'HOME': '/home/cltbld', 16:39:37 INFO - 'LANG': 'en_US.UTF-8', 16:39:37 INFO - 'LANGUAGE': 'en_US:en', 16:39:37 INFO - 'LOGNAME': 'cltbld', 16:39:37 INFO - 'MAIL': '/var/mail/cltbld', 16:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:37 INFO - 'MOZ_NO_REMOTE': '1', 16:39:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:37 INFO - 'NO_EM_RESTART': '1', 16:39:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:37 INFO - 'PWD': '/builds/slave/test', 16:39:37 INFO - 'SHELL': '/bin/bash', 16:39:37 INFO - 'SHLVL': '1', 16:39:37 INFO - 'TERM': 'linux', 16:39:37 INFO - 'TMOUT': '86400', 16:39:37 INFO - 'USER': 'cltbld', 16:39:37 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:37 INFO - '_': '/tools/buildbot/bin/python'} 16:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:39:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:39:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:39:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:40 INFO - Downloading docopt-0.6.1.tar.gz 16:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:39:40 INFO - Installing collected packages: blobuploader, requests, docopt 16:39:40 INFO - Running setup.py install for blobuploader 16:39:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:39:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:39:40 INFO - Running setup.py install for requests 16:39:40 INFO - Running setup.py install for docopt 16:39:40 INFO - Successfully installed blobuploader requests docopt 16:39:40 INFO - Cleaning up... 16:39:40 INFO - Return code: 0 16:39:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:40 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:40 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 16:39:40 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 16:39:40 INFO - Using env: {'DISPLAY': ':0', 16:39:40 INFO - 'HOME': '/home/cltbld', 16:39:40 INFO - 'LANG': 'en_US.UTF-8', 16:39:40 INFO - 'LANGUAGE': 'en_US:en', 16:39:40 INFO - 'LOGNAME': 'cltbld', 16:39:40 INFO - 'MAIL': '/var/mail/cltbld', 16:39:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:40 INFO - 'MOZ_NO_REMOTE': '1', 16:39:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:40 INFO - 'NO_EM_RESTART': '1', 16:39:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:40 INFO - 'PWD': '/builds/slave/test', 16:39:40 INFO - 'SHELL': '/bin/bash', 16:39:40 INFO - 'SHLVL': '1', 16:39:40 INFO - 'TERM': 'linux', 16:39:40 INFO - 'TMOUT': '86400', 16:39:40 INFO - 'USER': 'cltbld', 16:39:40 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:40 INFO - '_': '/tools/buildbot/bin/python'} 16:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:39:41 INFO - Running setup.py (path:/tmp/pip-aHvULh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:39:41 INFO - Running setup.py (path:/tmp/pip-aEeRqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:39:41 INFO - Running setup.py (path:/tmp/pip-enxy5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:39:41 INFO - Running setup.py (path:/tmp/pip-5GIO_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:39:41 INFO - Running setup.py (path:/tmp/pip-svRqpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:39:41 INFO - Running setup.py (path:/tmp/pip-Yh9Nyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:39:41 INFO - Running setup.py (path:/tmp/pip-ldgucg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:39:41 INFO - Running setup.py (path:/tmp/pip-VnLPFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:39:41 INFO - Running setup.py (path:/tmp/pip-wQbUAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:39:41 INFO - Running setup.py (path:/tmp/pip-Uk4kQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:39:41 INFO - Running setup.py (path:/tmp/pip-bRgedS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:39:41 INFO - Running setup.py (path:/tmp/pip-c35tjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:39:41 INFO - Running setup.py (path:/tmp/pip-jyGpB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:39:42 INFO - Running setup.py (path:/tmp/pip-Q7xBJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:42 INFO - Running setup.py (path:/tmp/pip-aU7lni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:39:42 INFO - Running setup.py (path:/tmp/pip-g6VYhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:39:42 INFO - Running setup.py (path:/tmp/pip-iHBQhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:39:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:39:42 INFO - Running setup.py install for manifestparser 16:39:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:39:42 INFO - Running setup.py install for mozcrash 16:39:42 INFO - Running setup.py install for mozdebug 16:39:42 INFO - Running setup.py install for mozdevice 16:39:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:39:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:39:42 INFO - Running setup.py install for mozfile 16:39:42 INFO - Running setup.py install for mozhttpd 16:39:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:39:42 INFO - Running setup.py install for mozinfo 16:39:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Running setup.py install for mozInstall 16:39:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Running setup.py install for mozleak 16:39:43 INFO - Running setup.py install for mozlog 16:39:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Running setup.py install for moznetwork 16:39:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Running setup.py install for mozprocess 16:39:43 INFO - Running setup.py install for mozprofile 16:39:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Running setup.py install for mozrunner 16:39:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:39:43 INFO - Running setup.py install for mozscreenshot 16:39:43 INFO - Running setup.py install for moztest 16:39:43 INFO - Running setup.py install for mozversion 16:39:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:39:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:39:44 INFO - Cleaning up... 16:39:44 INFO - Return code: 0 16:39:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:44 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')]} 16:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:44 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')]} 16:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:44 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:44 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 16:39:44 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 16:39:44 INFO - Using env: {'DISPLAY': ':0', 16:39:44 INFO - 'HOME': '/home/cltbld', 16:39:44 INFO - 'LANG': 'en_US.UTF-8', 16:39:44 INFO - 'LANGUAGE': 'en_US:en', 16:39:44 INFO - 'LOGNAME': 'cltbld', 16:39:44 INFO - 'MAIL': '/var/mail/cltbld', 16:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:44 INFO - 'MOZ_NO_REMOTE': '1', 16:39:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:44 INFO - 'NO_EM_RESTART': '1', 16:39:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:44 INFO - 'PWD': '/builds/slave/test', 16:39:44 INFO - 'SHELL': '/bin/bash', 16:39:44 INFO - 'SHLVL': '1', 16:39:44 INFO - 'TERM': 'linux', 16:39:44 INFO - 'TMOUT': '86400', 16:39:44 INFO - 'USER': 'cltbld', 16:39:44 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:44 INFO - '_': '/tools/buildbot/bin/python'} 16:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:39:44 INFO - Running setup.py (path:/tmp/pip-D1pU9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:39:44 INFO - Running setup.py (path:/tmp/pip-H0IOf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:39:44 INFO - Running setup.py (path:/tmp/pip-2Fur7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:39:44 INFO - Running setup.py (path:/tmp/pip-s6fBbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:39:44 INFO - Running setup.py (path:/tmp/pip-77A2FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:39:44 INFO - Running setup.py (path:/tmp/pip-N0gRjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:39:44 INFO - Running setup.py (path:/tmp/pip-4Iz0pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:39:44 INFO - Running setup.py (path:/tmp/pip-AWNrXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:39:44 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)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:39:44 INFO - Running setup.py (path:/tmp/pip-PQIh0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:39:45 INFO - Running setup.py (path:/tmp/pip-FT8c7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:39:45 INFO - Running setup.py (path:/tmp/pip-bFF8RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:39:45 INFO - Running setup.py (path:/tmp/pip-nWuhMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:39:45 INFO - Running setup.py (path:/tmp/pip-Xny8lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:39:45 INFO - Running setup.py (path:/tmp/pip-ItXKxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:45 INFO - Running setup.py (path:/tmp/pip-Wi5zGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:39:45 INFO - Running setup.py (path:/tmp/pip-g8F3EC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:39:45 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)) 16:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:39:45 INFO - Running setup.py (path:/tmp/pip-N3M3uH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:39:45 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)) 16:39:45 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)) 16:39:45 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)) 16:39:45 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)) 16:39:45 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)) 16:39:45 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)) 16:39:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:39:45 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 16:39:45 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 16:39:45 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 16:39:45 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 16:39:45 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 16:39:45 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 16:39:47 INFO - Downloading blessings-1.6.tar.gz 16:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:39:47 INFO - Installing collected packages: blessings 16:39:47 INFO - Running setup.py install for blessings 16:39:47 INFO - Successfully installed blessings 16:39:47 INFO - Cleaning up... 16:39:47 INFO - Return code: 0 16:39:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:39:48 INFO - Reading from file tmpfile_stdout 16:39:48 INFO - Current package versions: 16:39:48 INFO - argparse == 1.2.1 16:39:48 INFO - blessings == 1.6 16:39:48 INFO - blobuploader == 1.2.4 16:39:48 INFO - docopt == 0.6.1 16:39:48 INFO - functools32 == 3.2.3-2 16:39:48 INFO - jsonschema == 2.5.1 16:39:48 INFO - manifestparser == 1.1 16:39:48 INFO - mozInstall == 1.12 16:39:48 INFO - mozcrash == 1.0 16:39:48 INFO - mozdebug == 0.1 16:39:48 INFO - mozdevice == 0.49 16:39:48 INFO - mozfile == 1.2 16:39:48 INFO - mozhttpd == 0.7 16:39:48 INFO - mozinfo == 0.9 16:39:48 INFO - mozleak == 0.1 16:39:48 INFO - mozlog == 3.4 16:39:48 INFO - moznetwork == 0.27 16:39:48 INFO - mozprocess == 0.25 16:39:48 INFO - mozprofile == 0.28 16:39:48 INFO - mozrunner == 6.13 16:39:48 INFO - mozscreenshot == 0.1 16:39:48 INFO - mozsystemmonitor == 0.3 16:39:48 INFO - moztest == 0.8 16:39:48 INFO - mozversion == 1.4 16:39:48 INFO - psutil == 3.1.1 16:39:48 INFO - requests == 1.2.3 16:39:48 INFO - wsgiref == 0.1.2 16:39:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:48 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:48 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 16:39:48 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 16:39:48 INFO - Using env: {'DISPLAY': ':0', 16:39:48 INFO - 'HOME': '/home/cltbld', 16:39:48 INFO - 'LANG': 'en_US.UTF-8', 16:39:48 INFO - 'LANGUAGE': 'en_US:en', 16:39:48 INFO - 'LOGNAME': 'cltbld', 16:39:48 INFO - 'MAIL': '/var/mail/cltbld', 16:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:48 INFO - 'MOZ_NO_REMOTE': '1', 16:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:48 INFO - 'NO_EM_RESTART': '1', 16:39:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:48 INFO - 'PWD': '/builds/slave/test', 16:39:48 INFO - 'SHELL': '/bin/bash', 16:39:48 INFO - 'SHLVL': '1', 16:39:48 INFO - 'TERM': 'linux', 16:39:48 INFO - 'TMOUT': '86400', 16:39:48 INFO - 'USER': 'cltbld', 16:39:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:48 INFO - '_': '/tools/buildbot/bin/python'} 16:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 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)) 16:39:48 INFO - Cleaning up... 16:39:48 INFO - Return code: 0 16:39:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:48 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 0x1f801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2078a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, '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': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', '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 16:39:48 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 16:39:48 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 16:39:48 INFO - Using env: {'DISPLAY': ':0', 16:39:48 INFO - 'HOME': '/home/cltbld', 16:39:48 INFO - 'LANG': 'en_US.UTF-8', 16:39:48 INFO - 'LANGUAGE': 'en_US:en', 16:39:48 INFO - 'LOGNAME': 'cltbld', 16:39:48 INFO - 'MAIL': '/var/mail/cltbld', 16:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:48 INFO - 'MOZ_NO_REMOTE': '1', 16:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:48 INFO - 'NO_EM_RESTART': '1', 16:39:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:48 INFO - 'PWD': '/builds/slave/test', 16:39:48 INFO - 'SHELL': '/bin/bash', 16:39:48 INFO - 'SHLVL': '1', 16:39:48 INFO - 'TERM': 'linux', 16:39:48 INFO - 'TMOUT': '86400', 16:39:48 INFO - 'USER': 'cltbld', 16:39:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:48 INFO - '_': '/tools/buildbot/bin/python'} 16:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:39:48 INFO - Cleaning up... 16:39:48 INFO - Return code: 0 16:39:48 INFO - Running post-action listener: _resource_record_post_action 16:39:48 INFO - Running post-action listener: _start_resource_monitoring 16:39:48 INFO - Starting resource monitoring. 16:39:48 INFO - [mozharness: 2017-03-21 23:39:48.583249Z] Finished create-virtualenv step (success) 16:39:48 INFO - [mozharness: 2017-03-21 23:39:48.583962Z] Running install step. 16:39:48 INFO - Running pre-action listener: _resource_record_pre_action 16:39:48 INFO - Running main action method: install 16:39:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:39:48 INFO - Reading from file tmpfile_stdout 16:39:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:39:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:39:48 INFO - Reading from file tmpfile_stdout 16:39:48 INFO - Output received: 16:39:48 INFO - Usage: mozinstall [options] installer 16:39:48 INFO - Options: 16:39:48 INFO - -h, --help show this help message and exit 16:39:48 INFO - -d DEST, --destination=DEST 16:39:48 INFO - Directory to install application into. [default: 16:39:48 INFO - "/builds/slave/test"] 16:39:48 INFO - --app=APP Application being installed. [default: firefox] 16:39:48 INFO - mkdir: /builds/slave/test/build/application 16:39:48 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'] 16:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:40:04 INFO - Reading from file tmpfile_stdout 16:40:04 INFO - Output received: 16:40:04 INFO - /builds/slave/test/build/application/firefox/firefox 16:40:04 INFO - Running post-action listener: _resource_record_post_action 16:40:04 INFO - [mozharness: 2017-03-21 23:40:04.348601Z] Finished install step (success) 16:40:04 INFO - [mozharness: 2017-03-21 23:40:04.348870Z] Running run-tests step. 16:40:04 INFO - Running pre-action listener: _resource_record_pre_action 16:40:04 INFO - Running main action method: run_tests 16:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:40:04 INFO - Python 2.7.3 16:40:04 INFO - Return code: 0 16:40:04 INFO - grabbing minidump binary from tooltool 16:40: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')]} 16:40:04 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 0x221b290>, '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 0x221bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:40:04 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 16:40:04 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 16:40:04 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 16:40:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:40:04 INFO - Return code: 0 16:40:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:40:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:40:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:40:04 INFO - ENV: RUST_BACKTRACE is now 1 16:40:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:40:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:40:04 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/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:40:04 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/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 16:40:04 INFO - Using env: {'DISPLAY': ':0', 16:40:04 INFO - 'HOME': '/home/cltbld', 16:40:04 INFO - 'LANG': 'en_US.UTF-8', 16:40:04 INFO - 'LANGUAGE': 'en_US:en', 16:40:04 INFO - 'LOGNAME': 'cltbld', 16:40:04 INFO - 'MAIL': '/var/mail/cltbld', 16:40:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:40:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:04 INFO - 'MOZ_NO_REMOTE': '1', 16:40:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:04 INFO - 'NO_EM_RESTART': '1', 16:40:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:04 INFO - 'PWD': '/builds/slave/test', 16:40:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:40:04 INFO - 'RUST_BACKTRACE': '1', 16:40:04 INFO - 'SHELL': '/bin/bash', 16:40:04 INFO - 'SHLVL': '1', 16:40:04 INFO - 'TERM': 'linux', 16:40:04 INFO - 'TMOUT': '86400', 16:40:04 INFO - 'USER': 'cltbld', 16:40:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1490139535.259856-988796537', 16:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:04 INFO - '_': '/tools/buildbot/bin/python'} 16:40:04 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/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:40:04 INFO - mozversion application_buildid: 20170321230853 16:40:04 INFO - mozversion application_changeset: 8744e9f8eb99f1290aae81985812d57364f18708 16:40:04 INFO - mozversion application_display_name: Nightly 16:40:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:40:04 INFO - mozversion application_name: Firefox 16:40:04 INFO - mozversion application_remotingname: firefox 16:40:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:40:04 INFO - mozversion application_vendor: Mozilla 16:40:04 INFO - mozversion application_version: 55.0a1 16:40:04 INFO - mozversion platform_buildid: 20170321230853 16:40:04 INFO - mozversion platform_changeset: 8744e9f8eb99f1290aae81985812d57364f18708 16:40:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:40:04 INFO - mozversion platform_version: 55.0a1 16:40:04 INFO - using testdate: 1490139604 16:40:04 INFO - actual date: 1490139604 16:40:04 INFO - starting webserver on 'localhost:40358' 16:40:04 INFO - SUITE-START | Running 2 tests 16:40:04 INFO - TEST-START | dromaeo_css 16:40:04 INFO - operating with platform_type : linux_ 16:40:04 INFO - Initialising browser for dromaeo_css test... 16:40:04 INFO - TEST-INFO | started process 4161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCXsPO/profile http://localhost:40358/getInfo.html) 16:40:09 INFO - PID 4161 | __metrics Screen width/height:1600/1200 16:40:09 INFO - PID 4161 | colorDepth:24 16:40:09 INFO - PID 4161 | Browser inner width/height: 1024/697 16:40:09 INFO - PID 4161 | __metrics 16:40:09 ERROR - PID 4161 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 16:40:10 INFO - TEST-INFO | 4161: exit 0 16:40:10 INFO - Browser initialized. 16:40:10 INFO - Running cycle 1/1 for dromaeo_css test... 16:40:10 INFO - TEST-INFO | started process 4293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCXsPO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:23 INFO - PID 4293 | RSS: Main: 155488256 16:40:23 INFO - PID 4293 | 16:43:28 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:40358/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40358/tests/dromaeo/cssquery-ext.html) 16:43:30 INFO - PID 4293 | RSS: Main: 166752256 16:43:30 INFO - PID 4293 | 16:45:11 INFO - PID 4293 | ************************* 16:45:11 INFO - PID 4293 | A coding exception was thrown and uncaught in a Task. 16:45:11 INFO - PID 4293 | 16:45:11 ERROR - PID 4293 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:45:11 INFO - PID 4293 | Full stack: 16:45:11 INFO - PID 4293 | ************************* 16:46:32 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:40358/tests/dromaeo/cssquery-ext.html (next: http://localhost:40358/tests/dromaeo/cssquery-jquery.html) 16:46:32 INFO - PID 4293 | RSS: Main: 160313344 16:46:32 INFO - PID 4293 | 16:49:34 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:40358/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40358/tests/dromaeo/cssquery-mootools.html) 16:49:35 INFO - PID 4293 | RSS: Main: 168349696 16:49:35 INFO - PID 4293 | 16:52:37 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:40358/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40358/tests/dromaeo/cssquery-prototype.html) 16:52:38 INFO - PID 4293 | RSS: Main: 162144256 16:52:38 INFO - PID 4293 | 16:55:41 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:40358/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40358/tests/dromaeo/cssquery-yui.html) 16:55:41 INFO - PID 4293 | RSS: Main: 169308160 16:55:41 INFO - PID 4293 | 16:58:44 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:40358/tests/dromaeo/cssquery-yui.html (next: http://localhost:40358/tests/dromaeo/cssquery-dojo.html) 16:58:45 INFO - PID 4293 | RSS: Main: 162394112 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | __start_tp_report 16:58:45 INFO - PID 4293 | _x_x_mozilla_page_load 16:58:45 INFO - PID 4293 | _x_x_mozilla_page_load_details 16:58:45 INFO - PID 4293 | |i|pagename|runs| 16:58:45 INFO - PID 4293 | |0;dojo.html;2686.9596956045657;2735.5896615507686;2739.3288644282143;2773.681026681931;2782.5826126081083;5215.76529056192;5219.086659834523;5257.369115706116;5271.15661494161;5276.630635855487;5302.602304827144;5316.7873285068645;5323.041852466555;5335.866603334918;5388.811391601294;4786.449558300787;4830.6135509159185;4832.323474713543;4846.0792449434675;4850.733209673466;4851.539103785135;4859.41686997559;4908.779104940269;4925.679830811004;4956.182229932075;4730.9118902652235;4731.668783185179;4786.664933454659;4840.201366774478;4864;4744.667873248874;4755.334253204554;4788.138135135674;4797.904041919142;4836.927446088312;4674.462436819741;4759.048190361941;4765.142274390609;4790.257510085942;4790.688605240648;5130.948690513084;5170.1469257572835;5228;5238.292830467876;5315.893682126336;5664.490195882349;5674.659520428788;5714.7428365723645;5729.427057294279;5740.5981581289325;5641.182028605829;5651.7739290428335;5653.604247702662;5662.292213473316;5665.093585026417;5163.845084647392;5230.08473517133;5238.5023422774775;5265.921011184873;5307.65500242483;5695.572832037531;5751.712414379264;5763.711814409263;5778.930897783839;5786.681732504752;5460.153676180198;5478.315210598676;5529.861753456115;5544.833654990357;5571.609987300931;5687.971560142256;5696.629719068247;5704.486596206279;5712.828615141552;5717.4854263115685;5635.690037048002;5649.548036157097;5654.6324488908085;5660.066089095336;5687.488126068673;5483.725813709299;5549.445055494499;5634.295713035871;5677.716114194356;5678.403767604342;4704.835330763407;4705.317728929354;4712.976435117871;4732.787024583902;4747.382840230748;4041.312976793952;4051.6758659307184;4053.432519447221;4073.103917138225;4081.3469844824685;4758.357621721023;4813.542713442217;4856.854294371175;4903.975480122576;4952.529509696573;5704.515116215168;5711.6001879868845;5711.685857277889;5715.971420142956;5756.251687280626;4766.23740201573;4778.331033655291;4801.279808028824;4815.22956326986;4863.95136048635;4879.341288926021;4900.411950565884;4982.277569752366;5006.949930500648;5029.497050295015;5281.762320695655;5319.46805319465;5321.3348331458565;5333.333333333333;5340.412554619066;5570.359408667893;5597.972010139923;5725.685087320071;5743.282089738783;5799.623024503394;5214.504622060898;5275.287836142225;5323.228131920927;5332.226827110125;5365.704886231295;5501.807436739855;5568.387477377404;5606;5616.943830561642;5621.747021383966;5594.720263986703;5658.236138121301;5673.262475878109;5678;5691.573132015033;5443.755031023693;5555.388907220284;5569.888602228014;5606.299212598426;5610.747516361856;5493.011257973603;5509.531689806411;5521.365043020104;5524.696141712245;5536.058869992031;5406.810761623325;5459.344878614592;5477.780888764564;5503.147012213113;5542.889142217214;5415.891682166413;5417.377001644703;5445.482679145475;5452.645578037414;5477;5326.41409444953;5355.491228333302;5368.2752828367675;5368.355797304349;5408.567314614743 16:58:45 INFO - PID 4293 | |1;ext.html;659.7394029358403;721.6968873073308;746.1568427676726;1049.9002594753497;1050.7793363393687;30257.39485210297;31446.371072578535;31481.527777083335;31497.52753708696;31541.211469713267;31807.204819879513;32432.675673243262;32547;32565.83717081417;32569;7139.750108746195;7185.640717964094;7239.384652304534;7328.157261914868;7340.559566426032;7285.781426557185;7306.415486761073;7328.560286382807;7328.78013659591;7404.703811847547;7191.280871912793;7250.782476525686;7348.448866335047;7349.595772232525;7355.668994895243;7284.053073100516;7301.744438944665;7304.379127774146;7314.634268286564;7341.522801017917;7206;7294.963525182393;7340.339369456775;7366.410687145016;7450.366718828906;33111;33225.833870830735;33453.16367090818;33526;33684.32631347384;2585.133980116663;2603.9800995024875;2608.269684488327;2634.973650263492;2660.5477068898335;23566.175183868483;23869.641955370684;23972.760272397227;24115.758842411527;24279.115341545443;2651.7348265173327;2659.5611724065507;2673.3049407154085;2690.9596356054676;2702.9459410811673;104227.47886260596;106227.46886265597;113703.43148284289;141126.94786203938;141385.293073535;35861.46207806885;35913.999999999476;36040.27919441596;36140;36164.09589760277;40952.38571421431;41074.17851642951;41096.794516027825;41097.1780564387;41336;11283.548658053585;11318.377489238172;11380.601678941197;11427.828582571268;11525.596604118984;8968.506732129796;8969.50667713269;8985.371024028254;9033.05152958943;9039.141281578239;12830.486780528861;12926.224426534438;12971.41628626714;13071.215727056408;13135.671608209681;5444.891102177934;5454.427285135083;5486.064955248923;5512.4211957744665;5526.143447765603;834.3158609939791;845.9703910363107;860.8837806896114;873.3799002708022;901.6889173235223;20077.196912123643;20859.687104693443;20984.685229721566;21037.894810526155;21087.367378978655;936.3352020065829;943.1464524605243;951.2247518272586;972.6692924405736;977.1986970683971;10338.0695737384;10828.945855270673;10833.241673082966;10934.507947142238;11006;218749;219411;219916;220051;220695;41526.169476610296;41969.16061678749;41990.79004604957;42043.15913681709;42342;1158.8841115888345;1199.3283761093815;1206.6440400081997;1210.3827048205478;1215.3862299538853;19343.4196974091;19469.51326216856;20315.8984205078;20437.48906277355;20780.792192077886;28892.277693057;29515.55726664145;29518.852405737834;29529.60409552929;29599.112026639235;200549;200897;201340;201678;202517;27402.588961166;27633.447331052863;27744;27849.16452506428;27909;33894.8305258482;34304.485432719026;34853.30293394169;35158.64841351554;35258.471122932664;26564.734352656225;27652.58521122225;27987.72012279851;28615.570766438937;30056.098317050524;28812.711872881842;29254.853725731235;29752.256193595334;30498;30600.234994125323;29141.271468212617;29617.703822961495;29751.702482975757;29853.253668658457;29955.850220748758;26821.463570728865;27269.45461090807;27706.307342316602;27717.58423623607;27815.44369112566;21009.684854726886;21076.47308817292;21195.364139075853;21312.893435532722;21482.281417486876 16:58:45 INFO - PID 4293 | |2;jquery.html;410.13461596032386;416.9062679700977;433.0775448295131;440.8743508100191;441.8343121329501;16277.755833662504;16836.91581542092;17330.443600501054;17403.303867845272;17499.65000699987;17539.5615109622;17576.121193940286;18348.908255458704;18929.24283028677;19202.135903884322;5460.754266058037;5518.696471694056;5625.8593535161535;5744.913826292609;5773.48038676519;5715.2855893013375;5792.971035144818;5805.767769289223;5813.709314534278;5823.2138661280605;5464.535514481254;5630.380658127603;5722.971385143069;5743.081107022877;5769.423057694218;5551.250581171551;5553.250311207976;5575.8606034849045;5587.832365029056;5604.663720176782;5057.190849464068;5219.060569097561;5261.316028916254;5261.657992230531;5265.289185959885;20834.895825520925;21013.789862101337;22283.44291392712;22904.885475572682;22972.885135574215;1553.9861724924647;1564.6323114068184;1565.2643257668879;1570.8193557740892;1583.7307657698218;13563.321833908363;13894.513692020731;14170.929145354206;14863.256837158098;14973.775393369107;1733.3240036385819;1742.7647267618836;1745.5112568480845;1753.1497223846425;1794.901280429589;81587;81777;82304.58847705723;82984.17015829885;83736.58131709302;10805.675829725122;10819.837702434312;11094.445277736242;11140.33158010522;11260.211785174959;15102.773458398133;15201.6199595011;15338.539843804703;15386.9230653846;15451.768223476656;7303.504728602086;7308.6711098000715;7365.742199023009;7526.736564220226;7540.396768258526;6610.206775187008;6667.399934005865;6743.763968261185;6838.589684618939;6849.315068493211;6879.174499060145;6984.161900571862;7014.754483593152;7059.152901651834;7123.928760712431;2080.864743791649;2086.91652333908;2125.691774692661;2132.7867213278546;2226.4879077812193;835.3943391041568;864.2308345572445;865.8398196333699;878.8530967088011;887.3477993674644;16377.918110409371;16634.83365166357;16880.662386752443;17239.7414038787;17597.824021759876;846.6867259114044;857.5155037403847;862.6764963138822;864.1833467373398;867.6919693508839;9310.348275620641;9376.95311523438;9400.200982916529;9499.667511637059;9595.42427454355;434044.3400234922;440850;460094;460873;461322;82961;83708.58145709355;83983;84829.15170848212;87176.564117179;999.6251405722854;1015.3653966271081;1029.7580068683853;1032.509557959963;1039.2517387481003;14606.12363258208;15948.760768588249;16808.411705590246;17084.65830683404;17776.733348999518;35544.644553554135;35878.46182307215;35955;36018.91943241707;36741;45921.31118033299;47380.763096184295;47669.28496072492;47738.28392574184;47954.5204547964;11106.61126860556;11106.777864442827;11564.132690048113;11630.185886987829;12085.81871271914;11444.427778611203;11573.88426115728;11686.766264674829;11746.58886938953;12076.275423474619;10206.540705668114;10503.264771465923;10756.300840445347;10818.350898946088;10893.509792059218;10234.897651023692;10242.283040187413;10394.428306443218;10549.156067514734;10591.364518128938;10688;11032.724181895517;11047.447627618747;11069.446527673423;11594.246373985665;10596.8940310599;11197.888021119685;11372.715682108013;11538.538458461568;11588.536458541901;10310.690679279634;10389.636362727268;10549.578016879239;10562.577496900345;10987.725306867393 16:58:45 INFO - PID 4293 | |3;mootools.html;1236.462138969549;1248.8189212564178;1256.048286526662;1259.212991880075;1277.3421687830769;9343.112404321588;9756.170725488342;9928.404295742252;9929.056739609734;9969.803623565163;9916.652917147901;10070.093691567758;10164;10166.390016599009;10168.542415591297;2199.2082850173924;2211.380813372258;2224.6996655451467;2238.675392068149;2239.4177513846353;2102.9474263143466;2104.494921218904;2135.562209746998;2138.540213854021;2152.0853637204204;2088.1438610169835;2088.2482306369693;2106.9894650526726;2128.521082756383;2130.1692339987417;1806.331657286799;1808.448423230914;1830.8443782278523;1834.367143335547;1847.2610955617793;1762.5064981805115;1831.505493516743;2000.7999200080021;2018.1523760020827;2092.9790702092937;9452.52737363129;9692.70921872345;9697.66058187967;9698.175655069328;9706.174975127122;4937.703737775745;5104.081265372231;5107.7446127693465;5117.48825117489;5136.383633963911;3003.1140813460083;3028.5759993600914;3090.9072727817975;3098.752099832008;3117.766167537444;927.4388994658269;942.185009966381;972.6644307713898;984.1142971325793;987.5605355616754;13584.388702508411;14073.437062517487;14144.504942326968;14149.08030977983;14192.858071419258;4383.473983121982;4558.749268790182;4603.562661547147;4758.286257061469;4785.49752276013;10606.310589811637;10611.893881061245;10646.520906559223;10667.466626668636;10706.411147386969;5299.417064122943;5306.602004849652;5333.359996800409;5361.115415956401;5388.946110538923;4765.285207218875;4971.552560269521;5012.423571289349;5047.899042019139;5078.5175408336145;3561.5013898053776;3626.7642603230706;3637.436197389409;3682.834272457746;3750.362438385481;879.7184900831801;900.9324300677475;905.8278927003848;912.780932576177;917.5366439947917;684.3396122741478;696.9268226836109;705.4990956420999;713.5540287320425;723.4501778648265;5346.144616861284;5425.457454254543;5425.779199680041;5557.472040156179;5669.574781891375;531.7235037780409;538.9622726409114;541.8536995011325;544.6623093681864;553.4824938682345;3059.9541006884915;3063.5711000459955;3126.7967582107544;3151.732102771244;3219.6136463624516;11115.333080015225;11675.299482030936;12036.45835937385;12469.937650311871;12559.434825432878;11984.161108722306;12222.816657750142;12317.8298061684;12421.068419868547;12471.625851224479;8928.10718928115;8968.282537396992;9165.31260155491;9174.357794954289;9258.074192580823;5536.4186760389575;5539.390667026704;5550.195221692913;5656.943430565641;5778.942210578009;11016.889831101586;11212.327260364404;11271.098312134845;11426.94286528562;11472.426378680866;11985.400729963292;12024.278543287432;12186.329751863377;12291.446884890023;12310.81533777012;7431.777046688608;7478.738244161428;7512.812179695551;7534.6609402578115;7570.394368450621;8992.730218093468;9103.772405689911;9207.815843683224;9216.95391523038;9232.353735238468;8023.277904988579;8253.174682531939;8259.628316725642;8368.916310836814;8528.829423411622;8005.39959503028;8067.838643226985;8076.192380761877;8183.099859015384;8284.420090593601;6393.584417012879;6690.330966903271;6760.222574404006;6829.419499342612;6909.930900690929;5032.924506132338;5227.18978558324;5228.7647055883335;5275.762590683352;5278.049951008856;5171.146760784429;5191.532762051434;5289.444608316071;5295.920561191662;5332.17351310532 16:58:45 INFO - PID 4293 | |4;prototype.html;367.43965452684654;380.53574638940484;387.1019235373932;401.7348549957028;407.7614595461655;5172.758338162708;5387.78448862046;5432.483914028171;5471.863203419912;5511.72441377931;5265.287163427676;5407.432219616942;5430.402655707879;5454.699991500466;5458.863528411792;4365.934510982338;4377.839872433808;4391.780410979454;4393.868183954471;4397.472303323606;4003.15933653933;4156.314208155651;4379.824807007716;4388.978055109736;4393.582609652078;4121.505419349667;4144.751314921099;4152.8961775955695;4173.144505376391;4173.791310434482;4137.77242251676;4152.211079894825;4152.397902304164;4159.688023398257;4168.811888611762;3644.835982380799;3652.105234217607;3656.8537258509627;3670.8803814836465;3672.247189326182;5182.905504651359;5235;5260.473952604747;5282.603804714623;5307.973460132714;4781.2111001685025;4783.665143439961;4785.258284965836;4795.352627395292;4799.544043315879;5481.917771233435;5681.744321505542;5683.470855469318;5684.914726279109;5692.77228910843;3978.090516552042;3987.003249187703;4000.159966407029;4001.379786133154;4013.3979903014488;5802.564807639444;5902.734376953047;5997.520198384119;6029.8191054268;6064.878702425971;4070.8805078603623;4080.3879418087527;4080.4083407905687;4083.5099788025627;4092.242935057024;4138.317177665652;4146.771927543954;4151.667866570667;4156.713807405915;4164.56272091426;4536.228841097021;4714.434267887876;4724.385829842098;4732.763361831895;4779.665423420327;5675.77296908119;5683.630564013409;5703.942960570341;5739.16782066598;5744.1096630021575;1410.2032351721246;1416.3980308369107;1416.8228971378578;1418.489343836218;1418.5673369622157;898.2679116520015;963.5760265483165;970.0638883477478;971.3006635222629;975.7950830325569;4953.281774142729;4956.677815941952;4956.702597844141;4962.057209130254;4970.589264206857;5896.439838215363;5906.468417842415;5931.4958228550195;5931.530054917921;5940.495057920039;4838;4898.804047838118;4930;4936.703797772145;4962.255661650709;3094.8916787912317;3099.4730895747775;3114.8131112133347;3116.8597413116463;3121.3445176513187;5440.972795136078;5450.672959622435;5451.863703407446;5462.371827239918;5467.371252305956;5826.297584752906;5970.820875373745;5977.820665380045;6036.667983260875;6036.736223915944;5405.594580406485;5442.265294185234;5443.06771096263;5464.836054918359;5488.313960754906;5680;5830.62100963436;5862.765489380377;5890.764369425175;5904.114382842608;5710.743636399985;5756.798512052058;5769.365369809401;5775.942240577709;5779.190913272061;5160.174372100446;5267.446918073547;5293.650119219728;5462.535684466865;5505.347980956086;4103.179364127127;4105.85629502966;4108.568600297045;4115.1975364803575;4119.608637179464;5552.7778888843995;5873.324567674772;5876.853078673067;5904.439078287556;5924.851878703067;5631.971840140773;5667.716614169358;5676.318841739017;5685.175649530712;5700.657960522382;5674.687892165971;5707.258056452634;5727.11229759388;5740.598158128891;5771;5446.863828404322;5644.137434646639;5646.35066967304;5676;5692.601517893708;5792.681402522901;5795.82612521625;5819.15622234782;5824.767009319579;5835.67903765297;5484.601426636183;5642.7460764266525;5644.943550564606;5655.717214139194;5656.717164141694 16:58:45 INFO - PID 4293 | |5;yui.html;626.8307556959624;658.6937074260468;680.6086500262348;694.811871699394;708.5819366573722;12113.454894529745;12578.559750408738;12660.620181394574;12687;12695.555655552058;14129.505467308647;14359.784603230933;14737.557873263793;14795.704085918274;14830.332635031422;814.2752949874601;829.2246749289403;832.4131487301438;836.4437648963432;838.3167718309588;813.1299509524811;842.2924743215698;847.6778824046885;848.0586548930683;850.9106543812903;874.45783614159;874.5059041641484;874.5321253129576;874.7681864305932;878.0736323179044;866.7616405488541;867.1805144138791;867.2974890338783;867.4274978514181;868.8957325120963;634.0615888485067;634.0805831544233;634.8666779976211;635.2726128582793;640.5868215001344;12002.63992080239;12184;12212.206206596542;12260.421508213742;13395.86604133956;12041.518339266418;12497.56258530956;13406.46374145033;14039.947003974743;14257.572272831832;9542.475163865985;9552.856707149398;9749.853752193723;9838.34176336405;10227;2605.1142611512178;2622.93442663933;2643.444876575921;2664.254008877517;2723.400851812598;62219;63421.36578634201;64848;65106.348936510505;65311.34688653148;10464.267501274837;10475.580976760844;10479.633212837514;10607.522661480252;10771.10773597593;18731.25074997012;18762.24951001972;18977.43067707971;20912.37262882116;21002.894985524974;6191.783287585003;6279.183706118175;6361.745530178741;6407.903881441782;6477.77327793525;3278.7704860659524;3361.4453615153225;3407.693307602328;3409.454487282023;3450.5686789151355;3461.6365281645067;3523.6828685418436;3528.470729390561;3570.8036058016537;3583.3191693578137;567.7899177304424;573.9225204597409;574.1817909478975;582.5747204540625;592.9851753706192;432.1551367077356;435.81913505895335;441.2983356463239;453.24082162377886;459.7861994172659;7219.386352160021;7656.310932016034;7837.686492540349;7862.685492580347;7894.723684671009;460.1808780370945;466.9626429885601;494.57713654825324;500.11978917106154;502.3996324392389;3403.5745531808525;3458.7578869478893;3511.139770756181;3538.5399898013093;3565.2156525564333;59275.11087333693;59982.100268496004;61543.69228153831;61704;63372;42382.78808605937;42599;44328.335074973904;53088.469115308355;54160.729196353765;46825.531744682114;47450.28824567634;48680;48692.513074868795;49706;7904.453844239861;8335.124811894906;8343.541105239028;8445.282151016987;8535.53054582004;22864.314070577908;23015.99999999933;23954.760452395254;24422;24939.625905611792;38615.80692096522;40944.79527602462;41970.37044444275;42779;43753;10473.633422830164;10633.680989570325;10789.56841726322;11012.39431831225;11038.503267352828;11864;12110.455029523517;12319.876801231872;12448.81326780081;12999.41502632365;10378.429186394518;10589.788204236027;10604.151667866707;10973.61592344296;11015.72460688457;11867.217862712714;12006.579769708018;12014.159008869296;12272.877271227173;12403.162211963148;10075.546600402853;10380.325278857152;10451.059404653617;10843.837342439712;10847.349159050482;9663;9695.951520242354;10084.647037353658;10202.540885660312;10224.182065434896;8339.83320333602;9052.683156089506;9368.625254989724;9390.624375024923;9443 16:58:45 INFO - PID 4293 | __end_tp_report 16:58:45 INFO - PID 4293 | __start_cc_report 16:58:45 INFO - PID 4293 | _x_x_mozilla_cycle_collect,394 16:58:45 INFO - PID 4293 | __end_cc_report 16:58:45 INFO - PID 4293 | __startTimestamp1490140725010__endTimestamp 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | ------- Summary: start ------- 16:58:45 INFO - PID 4293 | Number of tests: 6 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | [#0] dojo.html Cycles:165 Average:5196.19 Median:5335.87 stddev:592.57 (11.1%) stddev-sans-first:560.74 16:58:45 INFO - PID 4293 | Values: 2687.0 2735.6 2739.3 2773.7 2782.6 5215.8 5219.1 5257.4 5271.2 5276.6 5302.6 5316.8 5323.0 5335.9 5388.8 4786.4 4830.6 4832.3 4846.1 4850.7 4851.5 4859.4 4908.8 4925.7 4956.2 4730.9 4731.7 4786.7 4840.2 4864.0 4744.7 4755.3 4788.1 4797.9 4836.9 4674.5 4759.0 4765.1 4790.3 4790.7 5130.9 5170.1 5228.0 5238.3 5315.9 5664.5 5674.7 5714.7 5729.4 5740.6 5641.2 5651.8 5653.6 5662.3 5665.1 5163.8 5230.1 5238.5 5265.9 5307.7 5695.6 5751.7 5763.7 5778.9 5786.7 5460.2 5478.3 5529.9 5544.8 5571.6 5688.0 5696.6 5704.5 5712.8 5717.5 5635.7 5649.5 5654.6 5660.1 5687.5 5483.7 5549.4 5634.3 5677.7 5678.4 4704.8 4705.3 4713.0 4732.8 4747.4 4041.3 4051.7 4053.4 4073.1 4081.3 4758.4 4813.5 4856.9 4904.0 4952.5 5704.5 5711.6 5711.7 5716.0 5756.3 4766.2 4778.3 4801.3 4815.2 4864.0 4879.3 4900.4 4982.3 5006.9 5029.5 5281.8 5319.5 5321.3 5333.3 5340.4 5570.4 5598.0 5725.7 5743.3 5799.6 5214.5 5275.3 5323.2 5332.2 5365.7 5501.8 5568.4 5606.0 5616.9 5621.7 5594.7 5658.2 5673.3 5678.0 5691.6 5443.8 5555.4 5569.9 5606.3 5610.7 5493.0 5509.5 5521.4 5524.7 5536.1 5406.8 5459.3 5477.8 5503.1 5542.9 5415.9 5417.4 5445.5 5452.6 5477.0 5326.4 5355.5 5368.3 5368.4 5408.6 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | [#1] ext.html Cycles:180 Average:31858.64 Median:29751.98 stddev:48448.26 (162.8%) stddev-sans-first:48527.54 16:58:45 INFO - PID 4293 | Values: 659.7 721.7 746.2 1049.9 1050.8 30257.4 31446.4 31481.5 31497.5 31541.2 31807.2 32432.7 32547.0 32565.8 32569.0 7139.8 7185.6 7239.4 7328.2 7340.6 7285.8 7306.4 7328.6 7328.8 7404.7 7191.3 7250.8 7348.4 7349.6 7355.7 7284.1 7301.7 7304.4 7314.6 7341.5 7206.0 7295.0 7340.3 7366.4 7450.4 33111.0 33225.8 33453.2 33526.0 33684.3 2585.1 2604.0 2608.3 2635.0 2660.5 23566.2 23869.6 23972.8 24115.8 24279.1 2651.7 2659.6 2673.3 2691.0 2702.9 104227.5 106227.5 113703.4 141126.9 141385.3 35861.5 35914.0 36040.3 36140.0 36164.1 40952.4 41074.2 41096.8 41097.2 41336.0 11283.5 11318.4 11380.6 11427.8 11525.6 8968.5 8969.5 8985.4 9033.1 9039.1 12830.5 12926.2 12971.4 13071.2 13135.7 5444.9 5454.4 5486.1 5512.4 5526.1 834.3 846.0 860.9 873.4 901.7 20077.2 20859.7 20984.7 21037.9 21087.4 936.3 943.1 951.2 972.7 977.2 10338.1 10828.9 10833.2 10934.5 11006.0 218749.0 219411.0 219916.0 220051.0 220695.0 41526.2 41969.2 41990.8 42043.2 42342.0 1158.9 1199.3 1206.6 1210.4 1215.4 19343.4 19469.5 20315.9 20437.5 20780.8 28892.3 29515.6 29518.9 29529.6 29599.1 200549.0 200897.0 201340.0 201678.0 202517.0 27402.6 27633.4 27744.0 27849.2 27909.0 33894.8 34304.5 34853.3 35158.6 35258.5 26564.7 27652.6 27987.7 28615.6 30056.1 28812.7 29254.9 29752.3 30498.0 30600.2 29141.3 29617.7 29751.7 29853.3 29955.9 26821.5 27269.5 27706.3 27717.6 27815.4 21009.7 21076.5 21195.4 21312.9 21482.3 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | [#2] jquery.html Cycles:180 Average:27151.33 Median:12254.97 stddev:74438.73 (607.4%) stddev-sans-first:74620.47 16:58:45 INFO - PID 4293 | Values: 410.1 416.9 433.1 440.9 441.8 16277.8 16836.9 17330.4 17403.3 17499.7 17539.6 17576.1 18348.9 18929.2 19202.1 5460.8 5518.7 5625.9 5744.9 5773.5 5715.3 5793.0 5805.8 5813.7 5823.2 5464.5 5630.4 5723.0 5743.1 5769.4 5551.3 5553.3 5575.9 5587.8 5604.7 5057.2 5219.1 5261.3 5261.7 5265.3 20834.9 21013.8 22283.4 22904.9 22972.9 1554.0 1564.6 1565.3 1570.8 1583.7 13563.3 13894.5 14170.9 14863.3 14973.8 1733.3 1742.8 1745.5 1753.1 1794.9 81587.0 81777.0 82304.6 82984.2 83736.6 10805.7 10819.8 11094.4 11140.3 11260.2 15102.8 15201.6 15338.5 15386.9 15451.8 7303.5 7308.7 7365.7 7526.7 7540.4 6610.2 6667.4 6743.8 6838.6 6849.3 6879.2 6984.2 7014.8 7059.2 7123.9 2080.9 2086.9 2125.7 2132.8 2226.5 835.4 864.2 865.8 878.9 887.3 16377.9 16634.8 16880.7 17239.7 17597.8 846.7 857.5 862.7 864.2 867.7 9310.3 9377.0 9400.2 9499.7 9595.4 434044.3 440850.0 460094.0 460873.0 461322.0 82961.0 83708.6 83983.0 84829.2 87176.6 999.6 1015.4 1029.8 1032.5 1039.3 14606.1 15948.8 16808.4 17084.7 17776.7 35544.6 35878.5 35955.0 36018.9 36741.0 45921.3 47380.8 47669.3 47738.3 47954.5 11106.6 11106.8 11564.1 11630.2 12085.8 11444.4 11573.9 11686.8 11746.6 12076.3 10206.5 10503.3 10756.3 10818.4 10893.5 10234.9 10242.3 10394.4 10549.2 10591.4 10688.0 11032.7 11047.4 11069.4 11594.2 10596.9 11197.9 11372.7 11538.5 11588.5 10310.7 10389.6 10549.6 10562.6 10987.7 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | [#3] mootools.html Cycles:180 Average:6022.09 Median:5091.30 stddev:3892.37 (76.5%) stddev-sans-first:3886.68 16:58:45 INFO - PID 4293 | Values: 1236.5 1248.8 1256.0 1259.2 1277.3 9343.1 9756.2 9928.4 9929.1 9969.8 9916.7 10070.1 10164.0 10166.4 10168.5 2199.2 2211.4 2224.7 2238.7 2239.4 2102.9 2104.5 2135.6 2138.5 2152.1 2088.1 2088.2 2107.0 2128.5 2130.2 1806.3 1808.4 1830.8 1834.4 1847.3 1762.5 1831.5 2000.8 2018.2 2093.0 9452.5 9692.7 9697.7 9698.2 9706.2 4937.7 5104.1 5107.7 5117.5 5136.4 3003.1 3028.6 3090.9 3098.8 3117.8 927.4 942.2 972.7 984.1 987.6 13584.4 14073.4 14144.5 14149.1 14192.9 4383.5 4558.7 4603.6 4758.3 4785.5 10606.3 10611.9 10646.5 10667.5 10706.4 5299.4 5306.6 5333.4 5361.1 5388.9 4765.3 4971.6 5012.4 5047.9 5078.5 3561.5 3626.8 3637.4 3682.8 3750.4 879.7 900.9 905.8 912.8 917.5 684.3 696.9 705.5 713.6 723.5 5346.1 5425.5 5425.8 5557.5 5669.6 531.7 539.0 541.9 544.7 553.5 3060.0 3063.6 3126.8 3151.7 3219.6 11115.3 11675.3 12036.5 12469.9 12559.4 11984.2 12222.8 12317.8 12421.1 12471.6 8928.1 8968.3 9165.3 9174.4 9258.1 5536.4 5539.4 5550.2 5656.9 5778.9 11016.9 11212.3 11271.1 11426.9 11472.4 11985.4 12024.3 12186.3 12291.4 12310.8 7431.8 7478.7 7512.8 7534.7 7570.4 8992.7 9103.8 9207.8 9217.0 9232.4 8023.3 8253.2 8259.6 8368.9 8528.8 8005.4 8067.8 8076.2 8183.1 8284.4 6393.6 6690.3 6760.2 6829.4 6909.9 5032.9 5227.2 5228.8 5275.8 5278.0 5171.1 5191.5 5289.4 5295.9 5332.2 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | [#4] prototype.html Cycles:180 Average:4709.75 Median:5396.69 stddev:1375.06 (25.5%) stddev-sans-first:1339.73 16:58:45 INFO - PID 4293 | Values: 367.4 380.5 387.1 401.7 407.8 5172.8 5387.8 5432.5 5471.9 5511.7 5265.3 5407.4 5430.4 5454.7 5458.9 4365.9 4377.8 4391.8 4393.9 4397.5 4003.2 4156.3 4379.8 4389.0 4393.6 4121.5 4144.8 4152.9 4173.1 4173.8 4137.8 4152.2 4152.4 4159.7 4168.8 3644.8 3652.1 3656.9 3670.9 3672.2 5182.9 5235.0 5260.5 5282.6 5308.0 4781.2 4783.7 4785.3 4795.4 4799.5 5481.9 5681.7 5683.5 5684.9 5692.8 3978.1 3987.0 4000.2 4001.4 4013.4 5802.6 5902.7 5997.5 6029.8 6064.9 4070.9 4080.4 4080.4 4083.5 4092.2 4138.3 4146.8 4151.7 4156.7 4164.6 4536.2 4714.4 4724.4 4732.8 4779.7 5675.8 5683.6 5703.9 5739.2 5744.1 1410.2 1416.4 1416.8 1418.5 1418.6 898.3 963.6 970.1 971.3 975.8 4953.3 4956.7 4956.7 4962.1 4970.6 5896.4 5906.5 5931.5 5931.5 5940.5 4838.0 4898.8 4930.0 4936.7 4962.3 3094.9 3099.5 3114.8 3116.9 3121.3 5441.0 5450.7 5451.9 5462.4 5467.4 5826.3 5970.8 5977.8 6036.7 6036.7 5405.6 5442.3 5443.1 5464.8 5488.3 5680.0 5830.6 5862.8 5890.8 5904.1 5710.7 5756.8 5769.4 5775.9 5779.2 5160.2 5267.4 5293.7 5462.5 5505.3 4103.2 4105.9 4108.6 4115.2 4119.6 5552.8 5873.3 5876.9 5904.4 5924.9 5632.0 5667.7 5676.3 5685.2 5700.7 5674.7 5707.3 5727.1 5740.6 5771.0 5446.9 5644.1 5646.4 5676.0 5692.6 5792.7 5795.8 5819.2 5824.8 5835.7 5484.6 5642.7 5644.9 5655.7 5656.7 16:58:45 INFO - PID 4293 | 16:58:45 INFO - PID 4293 | [#5] yui.html Cycles:180 Average:13789.08 Median:433.99 stddev:16926.62 (3900.3%) stddev-sans-first:16945.24 16:58:45 INFO - PID 4293 | Values: 626.8 658.7 680.6 694.8 708.6 12113.5 12578.6 12660.6 12687.0 12695.6 14129.5 14359.8 14737.6 14795.7 14830.3 814.3 829.2 832.4 836.4 838.3 813.1 842.3 847.7 848.1 850.9 874.5 874.5 874.5 874.8 878.1 866.8 867.2 867.3 867.4 868.9 634.1 634.1 634.9 635.3 640.6 12002.6 12184.0 12212.2 12260.4 13395.9 12041.5 12497.6 13406.5 14039.9 14257.6 9542.5 9552.9 9749.9 9838.3 10227.0 2605.1 2622.9 2643.4 2664.3 2723.4 62219.0 63421.4 64848.0 65106.3 65311.3 10464.3 10475.6 10479.6 10607.5 10771.1 18731.3 18762.2 18977.4 20912.4 21002.9 6191.8 6279.2 6361.7 6407.9 6477.8 3278.8 3361.4 3407.7 3409.5 3450.6 3461.6 3523.7 3528.5 3570.8 3583.3 567.8 573.9 574.2 582.6 593.0 432.2 435.8 441.3 453.2 459.8 7219.4 7656.3 7837.7 7862.7 7894.7 460.2 467.0 494.6 500.1 502.4 3403.6 3458.8 3511.1 3538.5 3565.2 59275.1 59982.1 61543.7 61704.0 63372.0 42382.8 42599.0 44328.3 53088.5 54160.7 46825.5 47450.3 48680.0 48692.5 49706.0 7904.5 8335.1 8343.5 8445.3 8535.5 22864.3 23016.0 23954.8 24422.0 24939.6 38615.8 40944.8 41970.4 42779.0 43753.0 10473.6 10633.7 10789.6 11012.4 11038.5 11864.0 12110.5 12319.9 12448.8 12999.4 10378.4 10589.8 10604.2 10973.6 11015.7 11867.2 12006.6 12014.2 12272.9 12403.2 10075.5 10380.3 10451.1 10843.8 10847.3 9663.0 9696.0 10084.6 10202.5 10224.2 8339.8 9052.7 9368.6 9390.6 9443.0 16:58:45 INFO - PID 4293 | -------- Summary: end -------- 16:58:45 INFO - PID 4293 | 16:58:45 INFO - TEST-INFO | 4293: exit 0 16:58:45 INFO - TEST-OK | dromaeo_css | took 1121242ms 16:58:45 INFO - TEST-START | kraken 16:58:45 INFO - operating with platform_type : linux_ 16:58:45 INFO - Initialising browser for kraken test... 16:58:45 INFO - TEST-INFO | started process 4672 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptua35O/profile http://localhost:40358/getInfo.html) 16:58:50 INFO - PID 4672 | __metrics Screen width/height:1600/1200 16:58:50 INFO - PID 4672 | colorDepth:24 16:58:50 INFO - PID 4672 | Browser inner width/height: 1024/697 16:58:50 INFO - PID 4672 | __metrics 16:58:51 ERROR - PID 4672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 16:58:52 INFO - TEST-INFO | 4672: exit 0 16:58:52 INFO - Browser initialized. 16:58:52 INFO - Running cycle 1/1 for kraken test... 16:58:52 INFO - TEST-INFO | started process 4806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptua35O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:59:04 INFO - PID 4806 | RSS: Main: 155385856 16:59:04 INFO - PID 4806 | 16:59:07 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40358/tests/kraken/driver.html?testName=audio-beat-detection) 16:59:08 INFO - PID 4806 | RSS: Main: 161284096 16:59:08 INFO - PID 4806 | 16:59:10 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40358/tests/kraken/driver.html?testName=audio-dft) 16:59:11 INFO - PID 4806 | RSS: Main: 161271808 16:59:11 INFO - PID 4806 | 16:59:14 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40358/tests/kraken/driver.html?testName=audio-fft) 16:59:15 INFO - PID 4806 | RSS: Main: 157884416 16:59:15 INFO - PID 4806 | 16:59:17 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40358/tests/kraken/driver.html?testName=audio-oscillator) 16:59:17 INFO - PID 4806 | RSS: Main: 158851072 16:59:17 INFO - PID 4806 | 16:59:20 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40358/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:59:20 INFO - PID 4806 | RSS: Main: 159268864 16:59:20 INFO - PID 4806 | 16:59:23 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40358/tests/kraken/driver.html?testName=imaging-darkroom) 16:59:24 INFO - PID 4806 | RSS: Main: 163573760 16:59:24 INFO - PID 4806 | 16:59:28 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40358/tests/kraken/driver.html?testName=imaging-desaturate) 16:59:28 INFO - PID 4806 | RSS: Main: 161554432 16:59:28 INFO - PID 4806 | 16:59:31 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40358/tests/kraken/driver.html?testName=json-parse-financial) 16:59:32 INFO - PID 4806 | RSS: Main: 162144256 16:59:32 INFO - PID 4806 | 16:59:34 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40358/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:59:34 INFO - PID 4806 | RSS: Main: 163213312 16:59:34 INFO - PID 4806 | 16:59:36 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:59:37 INFO - PID 4806 | RSS: Main: 163082240 16:59:37 INFO - PID 4806 | 16:59:39 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:59:40 INFO - PID 4806 | RSS: Main: 162861056 16:59:40 INFO - PID 4806 | 16:59:42 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:59:43 INFO - PID 4806 | RSS: Main: 163282944 16:59:43 INFO - PID 4806 | 16:59:46 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:59:47 INFO - PID 4806 | RSS: Main: 162902016 16:59:47 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | Cycle 1(1): loaded http://localhost:40358/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40358/tests/kraken/driver.html?testName=ai-astar) 16:59:49 INFO - PID 4806 | RSS: Main: 164995072 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | __start_tp_report 16:59:49 INFO - PID 4806 | _x_x_mozilla_page_load 16:59:49 INFO - PID 4806 | _x_x_mozilla_page_load_details 16:59:49 INFO - PID 4806 | |i|pagename|runs| 16:59:49 INFO - PID 4806 | |0;ai-astar;104;114;113;112;113;116;112;112;112;115 16:59:49 INFO - PID 4806 | |1;audio-beat-detection;159;125;122;181;126;129;127;126;147;126 16:59:49 INFO - PID 4806 | |2;audio-dft;165;166;165;169;166;169;165;166;168;166 16:59:49 INFO - PID 4806 | |3;audio-fft;75;76;76;105;76;76;77;77;97;76 16:59:49 INFO - PID 4806 | |4;audio-oscillator;85;85;119;86;86;85;108;86;85;106 16:59:49 INFO - PID 4806 | |5;imaging-gaussian-blur;114;114;115;114;115;117;115;115;116;115 16:59:49 INFO - PID 4806 | |6;imaging-darkroom;154;151;151;153;150;152;150;151;151;152 16:59:49 INFO - PID 4806 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;96 16:59:49 INFO - PID 4806 | |8;json-parse-financial;72;71;73;74;74;74;73;73;71;73 16:59:49 INFO - PID 4806 | |9;json-stringify-tinderbox;56;55;56;56;56;56;56;56;56;58 16:59:49 INFO - PID 4806 | |10;stanford-crypto-aes;92;83;84;83;96;84;83;83;94;83 16:59:49 INFO - PID 4806 | |11;stanford-crypto-ccm;141;135;142;145;139;146;148;134;218;141 16:59:49 INFO - PID 4806 | |12;stanford-crypto-pbkdf2;194;190;197;201;197;198;197;195;195;194 16:59:49 INFO - PID 4806 | |13;stanford-crypto-sha256-iterative;72;73;74;72;80;70;71;70;71;73 16:59:49 INFO - PID 4806 | __end_tp_report 16:59:49 INFO - PID 4806 | __start_cc_report 16:59:49 INFO - PID 4806 | _x_x_mozilla_cycle_collect,865 16:59:49 INFO - PID 4806 | __end_cc_report 16:59:49 INFO - PID 4806 | __startTimestamp1490140789642__endTimestamp 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | ------- Summary: start ------- 16:59:49 INFO - PID 4806 | Number of tests: 14 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:3.23 (2.9%) stddev-sans-first:1.48 16:59:49 INFO - PID 4806 | Values: 104.0 114.0 113.0 112.0 113.0 116.0 112.0 112.0 112.0 115.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:128.00 stddev:19.48 (15.2%) stddev-sans-first:18.93 16:59:49 INFO - PID 4806 | Values: 159.0 125.0 122.0 181.0 126.0 129.0 127.0 126.0 147.0 126.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#2] audio-dft Cycles:10 Average:166.50 Median:166.00 stddev:1.58 (1.0%) stddev-sans-first:1.58 16:59:49 INFO - PID 4806 | Values: 165.0 166.0 165.0 169.0 166.0 169.0 165.0 166.0 168.0 166.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:10.67 (14.0%) stddev-sans-first:11.09 16:59:49 INFO - PID 4806 | Values: 75.0 76.0 76.0 105.0 76.0 76.0 77.0 77.0 97.0 76.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:85.00 stddev:12.79 (15.1%) stddev-sans-first:13.23 16:59:49 INFO - PID 4806 | Values: 85.0 85.0 119.0 86.0 86.0 85.0 108.0 86.0 85.0 106.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:115.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 16:59:49 INFO - PID 4806 | Values: 114.0 114.0 115.0 114.0 115.0 117.0 115.0 115.0 116.0 115.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:151.50 stddev:1.27 (0.8%) stddev-sans-first:0.97 16:59:49 INFO - PID 4806 | Values: 154.0 151.0 151.0 153.0 150.0 152.0 150.0 151.0 151.0 152.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:59:49 INFO - PID 4806 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#8] json-parse-financial Cycles:10 Average:72.80 Median:73.00 stddev:1.14 (1.6%) stddev-sans-first:1.17 16:59:49 INFO - PID 4806 | Values: 72.0 71.0 73.0 74.0 74.0 74.0 73.0 73.0 71.0 73.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 16:59:49 INFO - PID 4806 | Values: 56.0 55.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 58.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:84.00 stddev:5.28 (6.3%) stddev-sans-first:5.21 16:59:49 INFO - PID 4806 | Values: 92.0 83.0 84.0 83.0 96.0 84.0 83.0 83.0 94.0 83.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:143.50 stddev:24.69 (17.2%) stddev-sans-first:26.02 16:59:49 INFO - PID 4806 | Values: 141.0 135.0 142.0 145.0 139.0 146.0 148.0 134.0 218.0 141.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.80 Median:197.00 stddev:2.94 (1.5%) stddev-sans-first:3.04 16:59:49 INFO - PID 4806 | Values: 194.0 190.0 197.0 201.0 197.0 198.0 197.0 195.0 195.0 194.0 16:59:49 INFO - PID 4806 | 16:59:49 INFO - PID 4806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:72.50 stddev:2.91 (4.0%) stddev-sans-first:3.08 16:59:49 INFO - PID 4806 | Values: 72.0 73.0 74.0 72.0 80.0 70.0 71.0 70.0 71.0 73.0 16:59:49 INFO - PID 4806 | -------- Summary: end -------- 16:59:49 INFO - PID 4806 | 16:59:50 INFO - TEST-INFO | 4806: exit 0 16:59:50 INFO - TEST-OK | kraken | took 64978ms 16:59:50 INFO - SUITE-END | took 1186s 16:59:51 INFO - Completed test suite (00:19:46) 16:59:51 INFO - javascript benchmark 16:59:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6505.542686729211, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2686.9596956045657, 2735.5896615507686, 2739.3288644282143, 2773.681026681931, 2782.5826126081083, 5215.76529056192, 5219.086659834523, 5257.369115706116, 5271.15661494161, 5276.630635855487, 5302.602304827144, 5316.7873285068645, 5323.041852466555, 5335.866603334918, 5388.811391601294, 4786.449558300787, 4830.6135509159185, 4832.323474713543, 4846.0792449434675, 4850.733209673466, 4851.539103785135, 4859.41686997559, 4908.779104940269, 4925.679830811004, 4956.182229932075, 4730.9118902652235, 4731.668783185179, 4786.664933454659, 4840.201366774478, 4864.0, 4744.667873248874, 4755.334253204554, 4788.138135135674, 4797.904041919142, 4836.927446088312, 4674.462436819741, 4759.048190361941, 4765.142274390609, 4790.257510085942, 4790.688605240648, 5130.948690513084, 5170.1469257572835, 5228.0, 5238.292830467876, 5315.893682126336, 5664.490195882349, 5674.659520428788, 5714.7428365723645, 5729.427057294279, 5740.5981581289325, 5641.182028605829, 5651.7739290428335, 5653.604247702662, 5662.292213473316, 5665.093585026417, 5163.845084647392, 5230.08473517133, 5238.5023422774775, 5265.921011184873, 5307.65500242483, 5695.572832037531, 5751.712414379264, 5763.711814409263, 5778.930897783839, 5786.681732504752, 5460.153676180198, 5478.315210598676, 5529.861753456115, 5544.833654990357, 5571.609987300931, 5687.971560142256, 5696.629719068247, 5704.486596206279, 5712.828615141552, 5717.4854263115685, 5635.690037048002, 5649.548036157097, 5654.6324488908085, 5660.066089095336, 5687.488126068673, 5483.725813709299, 5549.445055494499, 5634.295713035871, 5677.716114194356, 5678.403767604342, 4704.835330763407, 4705.317728929354, 4712.976435117871, 4732.787024583902, 4747.382840230748, 4041.312976793952, 4051.6758659307184, 4053.432519447221, 4073.103917138225, 4081.3469844824685, 4758.357621721023, 4813.542713442217, 4856.854294371175, 4903.975480122576, 4952.529509696573, 5704.515116215168, 5711.6001879868845, 5711.685857277889, 5715.971420142956, 5756.251687280626, 4766.23740201573, 4778.331033655291, 4801.279808028824, 4815.22956326986, 4863.95136048635, 4879.341288926021, 4900.411950565884, 4982.277569752366, 5006.949930500648, 5029.497050295015, 5281.762320695655, 5319.46805319465, 5321.3348331458565, 5333.333333333333, 5340.412554619066, 5570.359408667893, 5597.972010139923, 5725.685087320071, 5743.282089738783, 5799.623024503394, 5214.504622060898, 5275.287836142225, 5323.228131920927, 5332.226827110125, 5365.704886231295, 5501.807436739855, 5568.387477377404, 5606.0, 5616.943830561642, 5621.747021383966, 5594.720263986703, 5658.236138121301, 5673.262475878109, 5678.0, 5691.573132015033, 5443.755031023693, 5555.388907220284, 5569.888602228014, 5606.299212598426, 5610.747516361856, 5493.011257973603, 5509.531689806411, 5521.365043020104, 5524.696141712245, 5536.058869992031, 5406.810761623325, 5459.344878614592, 5477.780888764564, 5503.147012213113, 5542.889142217214, 5415.891682166413, 5417.377001644703, 5445.482679145475, 5452.645578037414, 5477.0, 5326.41409444953, 5355.491228333302, 5368.2752828367675, 5368.355797304349, 5408.567314614743], "value": 5153.849525318302, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.7394029358403, 721.6968873073308, 746.1568427676726, 1049.9002594753497, 1050.7793363393687, 30257.39485210297, 31446.371072578535, 31481.527777083335, 31497.52753708696, 31541.211469713267, 31807.204819879513, 32432.675673243262, 32547.0, 32565.83717081417, 32569.0, 7139.750108746195, 7185.640717964094, 7239.384652304534, 7328.157261914868, 7340.559566426032, 7285.781426557185, 7306.415486761073, 7328.560286382807, 7328.78013659591, 7404.703811847547, 7191.280871912793, 7250.782476525686, 7348.448866335047, 7349.595772232525, 7355.668994895243, 7284.053073100516, 7301.744438944665, 7304.379127774146, 7314.634268286564, 7341.522801017917, 7206.0, 7294.963525182393, 7340.339369456775, 7366.410687145016, 7450.366718828906, 33111.0, 33225.833870830735, 33453.16367090818, 33526.0, 33684.32631347384, 2585.133980116663, 2603.9800995024875, 2608.269684488327, 2634.973650263492, 2660.5477068898335, 23566.175183868483, 23869.641955370684, 23972.760272397227, 24115.758842411527, 24279.115341545443, 2651.7348265173327, 2659.5611724065507, 2673.3049407154085, 2690.9596356054676, 2702.9459410811673, 104227.47886260596, 106227.46886265597, 113703.43148284289, 141126.94786203938, 141385.293073535, 35861.46207806885, 35913.999999999476, 36040.27919441596, 36140.0, 36164.09589760277, 40952.38571421431, 41074.17851642951, 41096.794516027825, 41097.1780564387, 41336.0, 11283.548658053585, 11318.377489238172, 11380.601678941197, 11427.828582571268, 11525.596604118984, 8968.506732129796, 8969.50667713269, 8985.371024028254, 9033.05152958943, 9039.141281578239, 12830.486780528861, 12926.224426534438, 12971.41628626714, 13071.215727056408, 13135.671608209681, 5444.891102177934, 5454.427285135083, 5486.064955248923, 5512.4211957744665, 5526.143447765603, 834.3158609939791, 845.9703910363107, 860.8837806896114, 873.3799002708022, 901.6889173235223, 20077.196912123643, 20859.687104693443, 20984.685229721566, 21037.894810526155, 21087.367378978655, 936.3352020065829, 943.1464524605243, 951.2247518272586, 972.6692924405736, 977.1986970683971, 10338.0695737384, 10828.945855270673, 10833.241673082966, 10934.507947142238, 11006.0, 218749.0, 219411.0, 219916.0, 220051.0, 220695.0, 41526.169476610296, 41969.16061678749, 41990.79004604957, 42043.15913681709, 42342.0, 1158.8841115888345, 1199.3283761093815, 1206.6440400081997, 1210.3827048205478, 1215.3862299538853, 19343.4196974091, 19469.51326216856, 20315.8984205078, 20437.48906277355, 20780.792192077886, 28892.277693057, 29515.55726664145, 29518.852405737834, 29529.60409552929, 29599.112026639235, 200549.0, 200897.0, 201340.0, 201678.0, 202517.0, 27402.588961166, 27633.447331052863, 27744.0, 27849.16452506428, 27909.0, 33894.8305258482, 34304.485432719026, 34853.30293394169, 35158.64841351554, 35258.471122932664, 26564.734352656225, 27652.58521122225, 27987.72012279851, 28615.570766438937, 30056.098317050524, 28812.711872881842, 29254.853725731235, 29752.256193595334, 30498.0, 30600.234994125323, 29141.271468212617, 29617.703822961495, 29751.702482975757, 29853.253668658457, 29955.850220748758, 26821.463570728865, 27269.45461090807, 27706.307342316602, 27717.58423623607, 27815.44369112566, 21009.684854726886, 21076.47308817292, 21195.364139075853, 21312.893435532722, 21482.281417486876], "value": 14352.284572689296, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.13461596032386, 416.9062679700977, 433.0775448295131, 440.8743508100191, 441.8343121329501, 16277.755833662504, 16836.91581542092, 17330.443600501054, 17403.303867845272, 17499.65000699987, 17539.5615109622, 17576.121193940286, 18348.908255458704, 18929.24283028677, 19202.135903884322, 5460.754266058037, 5518.696471694056, 5625.8593535161535, 5744.913826292609, 5773.48038676519, 5715.2855893013375, 5792.971035144818, 5805.767769289223, 5813.709314534278, 5823.2138661280605, 5464.535514481254, 5630.380658127603, 5722.971385143069, 5743.081107022877, 5769.423057694218, 5551.250581171551, 5553.250311207976, 5575.8606034849045, 5587.832365029056, 5604.663720176782, 5057.190849464068, 5219.060569097561, 5261.316028916254, 5261.657992230531, 5265.289185959885, 20834.895825520925, 21013.789862101337, 22283.44291392712, 22904.885475572682, 22972.885135574215, 1553.9861724924647, 1564.6323114068184, 1565.2643257668879, 1570.8193557740892, 1583.7307657698218, 13563.321833908363, 13894.513692020731, 14170.929145354206, 14863.256837158098, 14973.775393369107, 1733.3240036385819, 1742.7647267618836, 1745.5112568480845, 1753.1497223846425, 1794.901280429589, 81587.0, 81777.0, 82304.58847705723, 82984.17015829885, 83736.58131709302, 10805.675829725122, 10819.837702434312, 11094.445277736242, 11140.33158010522, 11260.211785174959, 15102.773458398133, 15201.6199595011, 15338.539843804703, 15386.9230653846, 15451.768223476656, 7303.504728602086, 7308.6711098000715, 7365.742199023009, 7526.736564220226, 7540.396768258526, 6610.206775187008, 6667.399934005865, 6743.763968261185, 6838.589684618939, 6849.315068493211, 6879.174499060145, 6984.161900571862, 7014.754483593152, 7059.152901651834, 7123.928760712431, 2080.864743791649, 2086.91652333908, 2125.691774692661, 2132.7867213278546, 2226.4879077812193, 835.3943391041568, 864.2308345572445, 865.8398196333699, 878.8530967088011, 887.3477993674644, 16377.918110409371, 16634.83365166357, 16880.662386752443, 17239.7414038787, 17597.824021759876, 846.6867259114044, 857.5155037403847, 862.6764963138822, 864.1833467373398, 867.6919693508839, 9310.348275620641, 9376.95311523438, 9400.200982916529, 9499.667511637059, 9595.42427454355, 434044.3400234922, 440850.0, 460094.0, 460873.0, 461322.0, 82961.0, 83708.58145709355, 83983.0, 84829.15170848212, 87176.564117179, 999.6251405722854, 1015.3653966271081, 1029.7580068683853, 1032.509557959963, 1039.2517387481003, 14606.12363258208, 15948.760768588249, 16808.411705590246, 17084.65830683404, 17776.733348999518, 35544.644553554135, 35878.46182307215, 35955.0, 36018.91943241707, 36741.0, 45921.31118033299, 47380.763096184295, 47669.28496072492, 47738.28392574184, 47954.5204547964, 11106.61126860556, 11106.777864442827, 11564.132690048113, 11630.185886987829, 12085.81871271914, 11444.427778611203, 11573.88426115728, 11686.766264674829, 11746.58886938953, 12076.275423474619, 10206.540705668114, 10503.264771465923, 10756.300840445347, 10818.350898946088, 10893.509792059218, 10234.897651023692, 10242.283040187413, 10394.428306443218, 10549.156067514734, 10591.364518128938, 10688.0, 11032.724181895517, 11047.447627618747, 11069.446527673423, 11594.246373985665, 10596.8940310599, 11197.888021119685, 11372.715682108013, 11538.538458461568, 11588.536458541901, 10310.690679279634, 10389.636362727268, 10549.578016879239, 10562.577496900345, 10987.725306867393], "value": 8924.136127001528, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.462138969549, 1248.8189212564178, 1256.048286526662, 1259.212991880075, 1277.3421687830769, 9343.112404321588, 9756.170725488342, 9928.404295742252, 9929.056739609734, 9969.803623565163, 9916.652917147901, 10070.093691567758, 10164.0, 10166.390016599009, 10168.542415591297, 2199.2082850173924, 2211.380813372258, 2224.6996655451467, 2238.675392068149, 2239.4177513846353, 2102.9474263143466, 2104.494921218904, 2135.562209746998, 2138.540213854021, 2152.0853637204204, 2088.1438610169835, 2088.2482306369693, 2106.9894650526726, 2128.521082756383, 2130.1692339987417, 1806.331657286799, 1808.448423230914, 1830.8443782278523, 1834.367143335547, 1847.2610955617793, 1762.5064981805115, 1831.505493516743, 2000.7999200080021, 2018.1523760020827, 2092.9790702092937, 9452.52737363129, 9692.70921872345, 9697.66058187967, 9698.175655069328, 9706.174975127122, 4937.703737775745, 5104.081265372231, 5107.7446127693465, 5117.48825117489, 5136.383633963911, 3003.1140813460083, 3028.5759993600914, 3090.9072727817975, 3098.752099832008, 3117.766167537444, 927.4388994658269, 942.185009966381, 972.6644307713898, 984.1142971325793, 987.5605355616754, 13584.388702508411, 14073.437062517487, 14144.504942326968, 14149.08030977983, 14192.858071419258, 4383.473983121982, 4558.749268790182, 4603.562661547147, 4758.286257061469, 4785.49752276013, 10606.310589811637, 10611.893881061245, 10646.520906559223, 10667.466626668636, 10706.411147386969, 5299.417064122943, 5306.602004849652, 5333.359996800409, 5361.115415956401, 5388.946110538923, 4765.285207218875, 4971.552560269521, 5012.423571289349, 5047.899042019139, 5078.5175408336145, 3561.5013898053776, 3626.7642603230706, 3637.436197389409, 3682.834272457746, 3750.362438385481, 879.7184900831801, 900.9324300677475, 905.8278927003848, 912.780932576177, 917.5366439947917, 684.3396122741478, 696.9268226836109, 705.4990956420999, 713.5540287320425, 723.4501778648265, 5346.144616861284, 5425.457454254543, 5425.779199680041, 5557.472040156179, 5669.574781891375, 531.7235037780409, 538.9622726409114, 541.8536995011325, 544.6623093681864, 553.4824938682345, 3059.9541006884915, 3063.5711000459955, 3126.7967582107544, 3151.732102771244, 3219.6136463624516, 11115.333080015225, 11675.299482030936, 12036.45835937385, 12469.937650311871, 12559.434825432878, 11984.161108722306, 12222.816657750142, 12317.8298061684, 12421.068419868547, 12471.625851224479, 8928.10718928115, 8968.282537396992, 9165.31260155491, 9174.357794954289, 9258.074192580823, 5536.4186760389575, 5539.390667026704, 5550.195221692913, 5656.943430565641, 5778.942210578009, 11016.889831101586, 11212.327260364404, 11271.098312134845, 11426.94286528562, 11472.426378680866, 11985.400729963292, 12024.278543287432, 12186.329751863377, 12291.446884890023, 12310.81533777012, 7431.777046688608, 7478.738244161428, 7512.812179695551, 7534.6609402578115, 7570.394368450621, 8992.730218093468, 9103.772405689911, 9207.815843683224, 9216.95391523038, 9232.353735238468, 8023.277904988579, 8253.174682531939, 8259.628316725642, 8368.916310836814, 8528.829423411622, 8005.39959503028, 8067.838643226985, 8076.192380761877, 8183.099859015384, 8284.420090593601, 6393.584417012879, 6690.330966903271, 6760.222574404006, 6829.419499342612, 6909.930900690929, 5032.924506132338, 5227.18978558324, 5228.7647055883335, 5275.762590683352, 5278.049951008856, 5171.146760784429, 5191.532762051434, 5289.444608316071, 5295.920561191662, 5332.17351310532], "value": 4450.874903422271, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.43965452684654, 380.53574638940484, 387.1019235373932, 401.7348549957028, 407.7614595461655, 5172.758338162708, 5387.78448862046, 5432.483914028171, 5471.863203419912, 5511.72441377931, 5265.287163427676, 5407.432219616942, 5430.402655707879, 5454.699991500466, 5458.863528411792, 4365.934510982338, 4377.839872433808, 4391.780410979454, 4393.868183954471, 4397.472303323606, 4003.15933653933, 4156.314208155651, 4379.824807007716, 4388.978055109736, 4393.582609652078, 4121.505419349667, 4144.751314921099, 4152.8961775955695, 4173.144505376391, 4173.791310434482, 4137.77242251676, 4152.211079894825, 4152.397902304164, 4159.688023398257, 4168.811888611762, 3644.835982380799, 3652.105234217607, 3656.8537258509627, 3670.8803814836465, 3672.247189326182, 5182.905504651359, 5235.0, 5260.473952604747, 5282.603804714623, 5307.973460132714, 4781.2111001685025, 4783.665143439961, 4785.258284965836, 4795.352627395292, 4799.544043315879, 5481.917771233435, 5681.744321505542, 5683.470855469318, 5684.914726279109, 5692.77228910843, 3978.090516552042, 3987.003249187703, 4000.159966407029, 4001.379786133154, 4013.3979903014488, 5802.564807639444, 5902.734376953047, 5997.520198384119, 6029.8191054268, 6064.878702425971, 4070.8805078603623, 4080.3879418087527, 4080.4083407905687, 4083.5099788025627, 4092.242935057024, 4138.317177665652, 4146.771927543954, 4151.667866570667, 4156.713807405915, 4164.56272091426, 4536.228841097021, 4714.434267887876, 4724.385829842098, 4732.763361831895, 4779.665423420327, 5675.77296908119, 5683.630564013409, 5703.942960570341, 5739.16782066598, 5744.1096630021575, 1410.2032351721246, 1416.3980308369107, 1416.8228971378578, 1418.489343836218, 1418.5673369622157, 898.2679116520015, 963.5760265483165, 970.0638883477478, 971.3006635222629, 975.7950830325569, 4953.281774142729, 4956.677815941952, 4956.702597844141, 4962.057209130254, 4970.589264206857, 5896.439838215363, 5906.468417842415, 5931.4958228550195, 5931.530054917921, 5940.495057920039, 4838.0, 4898.804047838118, 4930.0, 4936.703797772145, 4962.255661650709, 3094.8916787912317, 3099.4730895747775, 3114.8131112133347, 3116.8597413116463, 3121.3445176513187, 5440.972795136078, 5450.672959622435, 5451.863703407446, 5462.371827239918, 5467.371252305956, 5826.297584752906, 5970.820875373745, 5977.820665380045, 6036.667983260875, 6036.736223915944, 5405.594580406485, 5442.265294185234, 5443.06771096263, 5464.836054918359, 5488.313960754906, 5680.0, 5830.62100963436, 5862.765489380377, 5890.764369425175, 5904.114382842608, 5710.743636399985, 5756.798512052058, 5769.365369809401, 5775.942240577709, 5779.190913272061, 5160.174372100446, 5267.446918073547, 5293.650119219728, 5462.535684466865, 5505.347980956086, 4103.179364127127, 4105.85629502966, 4108.568600297045, 4115.1975364803575, 4119.608637179464, 5552.7778888843995, 5873.324567674772, 5876.853078673067, 5904.439078287556, 5924.851878703067, 5631.971840140773, 5667.716614169358, 5676.318841739017, 5685.175649530712, 5700.657960522382, 5674.687892165971, 5707.258056452634, 5727.11229759388, 5740.598158128891, 5771.0, 5446.863828404322, 5644.137434646639, 5646.35066967304, 5676.0, 5692.601517893708, 5792.681402522901, 5795.82612521625, 5819.15622234782, 5824.767009319579, 5835.67903765297, 5484.601426636183, 5642.7460764266525, 5644.943550564606, 5655.717214139194, 5656.717164141694], "value": 4286.3308998807015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [626.8307556959624, 658.6937074260468, 680.6086500262348, 694.811871699394, 708.5819366573722, 12113.454894529745, 12578.559750408738, 12660.620181394574, 12687.0, 12695.555655552058, 14129.505467308647, 14359.784603230933, 14737.557873263793, 14795.704085918274, 14830.332635031422, 814.2752949874601, 829.2246749289403, 832.4131487301438, 836.4437648963432, 838.3167718309588, 813.1299509524811, 842.2924743215698, 847.6778824046885, 848.0586548930683, 850.9106543812903, 874.45783614159, 874.5059041641484, 874.5321253129576, 874.7681864305932, 878.0736323179044, 866.7616405488541, 867.1805144138791, 867.2974890338783, 867.4274978514181, 868.8957325120963, 634.0615888485067, 634.0805831544233, 634.8666779976211, 635.2726128582793, 640.5868215001344, 12002.63992080239, 12184.0, 12212.206206596542, 12260.421508213742, 13395.86604133956, 12041.518339266418, 12497.56258530956, 13406.46374145033, 14039.947003974743, 14257.572272831832, 9542.475163865985, 9552.856707149398, 9749.853752193723, 9838.34176336405, 10227.0, 2605.1142611512178, 2622.93442663933, 2643.444876575921, 2664.254008877517, 2723.400851812598, 62219.0, 63421.36578634201, 64848.0, 65106.348936510505, 65311.34688653148, 10464.267501274837, 10475.580976760844, 10479.633212837514, 10607.522661480252, 10771.10773597593, 18731.25074997012, 18762.24951001972, 18977.43067707971, 20912.37262882116, 21002.894985524974, 6191.783287585003, 6279.183706118175, 6361.745530178741, 6407.903881441782, 6477.77327793525, 3278.7704860659524, 3361.4453615153225, 3407.693307602328, 3409.454487282023, 3450.5686789151355, 3461.6365281645067, 3523.6828685418436, 3528.470729390561, 3570.8036058016537, 3583.3191693578137, 567.7899177304424, 573.9225204597409, 574.1817909478975, 582.5747204540625, 592.9851753706192, 432.1551367077356, 435.81913505895335, 441.2983356463239, 453.24082162377886, 459.7861994172659, 7219.386352160021, 7656.310932016034, 7837.686492540349, 7862.685492580347, 7894.723684671009, 460.1808780370945, 466.9626429885601, 494.57713654825324, 500.11978917106154, 502.3996324392389, 3403.5745531808525, 3458.7578869478893, 3511.139770756181, 3538.5399898013093, 3565.2156525564333, 59275.11087333693, 59982.100268496004, 61543.69228153831, 61704.0, 63372.0, 42382.78808605937, 42599.0, 44328.335074973904, 53088.469115308355, 54160.729196353765, 46825.531744682114, 47450.28824567634, 48680.0, 48692.513074868795, 49706.0, 7904.453844239861, 8335.124811894906, 8343.541105239028, 8445.282151016987, 8535.53054582004, 22864.314070577908, 23015.99999999933, 23954.760452395254, 24422.0, 24939.625905611792, 38615.80692096522, 40944.79527602462, 41970.37044444275, 42779.0, 43753.0, 10473.633422830164, 10633.680989570325, 10789.56841726322, 11012.39431831225, 11038.503267352828, 11864.0, 12110.455029523517, 12319.876801231872, 12448.81326780081, 12999.41502632365, 10378.429186394518, 10589.788204236027, 10604.151667866707, 10973.61592344296, 11015.72460688457, 11867.217862712714, 12006.579769708018, 12014.159008869296, 12272.877271227173, 12403.162211963148, 10075.546600402853, 10380.325278857152, 10451.059404653617, 10843.837342439712, 10847.349159050482, 9663.0, 9695.951520242354, 10084.647037353658, 10202.540885660312, 10224.182065434896, 8339.83320333602, 9052.683156089506, 9368.625254989724, 9390.624375024923, 9443.0], "value": 6018.904579238325, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 113.0, 112.0, 113.0, 116.0, 112.0, 112.0, 112.0, 115.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 125.0, 122.0, 181.0, 126.0, 129.0, 127.0, 126.0, 147.0, 126.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 165.0, 169.0, 166.0, 169.0, 165.0, 166.0, 168.0, 166.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 105.0, 76.0, 76.0, 77.0, 77.0, 97.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 119.0, 86.0, 86.0, 85.0, 108.0, 86.0, 85.0, 106.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 115.0, 117.0, 115.0, 115.0, 116.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 151.0, 153.0, 150.0, 152.0, 150.0, 151.0, 151.0, 152.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 73.0, 74.0, 74.0, 74.0, 73.0, 73.0, 71.0, 73.0], "value": 72.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 58.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 83.0, 84.0, 83.0, 96.0, 84.0, 83.0, 83.0, 94.0, 83.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 135.0, 142.0, 145.0, 139.0, 146.0, 148.0, 134.0, 218.0, 141.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 190.0, 197.0, 201.0, 197.0, 198.0, 197.0, 195.0, 195.0, 194.0], "value": 195.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 74.0, 72.0, 80.0, 70.0, 71.0, 70.0, 71.0, 73.0], "value": 72.6, "unit": "score"}]}]} 16:59:51 INFO - Return code: 0 16:59:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:59:51 ERROR - # TBPL SUCCESS # 16:59:51 INFO - Running post-action listener: _resource_record_post_action 16:59:51 INFO - [mozharness: 2017-03-21 23:59:51.458266Z] Finished run-tests step (success) 16:59:51 INFO - Running post-run listener: _resource_record_post_run 16:59:51 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 362795008; Read time: 32; Write time: 243440 16:59:51 INFO - TinderboxPrint: CPU usage
13.3% 16:59:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 32 16:59:51 INFO - TinderboxPrint: I/O write bytes / time
362,795,008 / 243,440 16:59:51 INFO - TinderboxPrint: CPU idle
8,311.2 (86.6%) 16:59:51 INFO - TinderboxPrint: CPU user
1,221.2 (12.7%) 16:59:51 INFO - TinderboxPrint: Swap in / out
0 / 0 16:59:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105451520; Read time: 0; Write time: 119008 16:59:51 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 257343488; Read time: 32; Write time: 124432 16:59:52 INFO - Running post-run listener: _upload_blobber_files 16:59:52 INFO - Blob upload gear active. 16:59:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:59:52 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 16:59:52 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'] 16:59:52 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 16:59:52 INFO - (blobuploader) - INFO - Open directory for files ... 16:59:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:59:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:59:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:53 INFO - (blobuploader) - INFO - Done attempting. 16:59:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:59:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:56 INFO - (blobuploader) - INFO - Done attempting. 16:59:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:57 INFO - (blobuploader) - INFO - Done attempting. 16:59:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:59:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:57 INFO - (blobuploader) - INFO - Done attempting. 16:59:57 INFO - (blobuploader) - INFO - Iteration through files over. 16:59:57 INFO - Return code: 0 16:59:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:59:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:59:57 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/20bce4f356f25b70826b64ad1f88786d39fc434ead3ae85b011bd048fd5d982bf04753280b1ea613a3bd5d8161031e91d81c18ca8b253acb3c092c26dd02f60f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14a58cd821222e03d6898ec3a0e09b9c5dfa445f41a76e89ccf22e997754d19a092200a44e14a657fb027fddafdc22e2514957b26a55bf6e4df284ef516a4787", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe48ca6afed5ac1f23c37abecc1fb9fdb34f383739be7189a86b5075a2c7f6a16997ed39ea3402b48a51f0d517a9ab28d91c49056793264b9e243613d617669e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c1359aedefa88945a3e30695ea5e552c982fe37bc21b7c1736d62055d19c49111fe51864f9026d05ed0528655740f21dee5cdae6a3e576fbe4ce3268778c556"} 16:59:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:59:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:59:57 INFO - Contents: 16:59:57 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/20bce4f356f25b70826b64ad1f88786d39fc434ead3ae85b011bd048fd5d982bf04753280b1ea613a3bd5d8161031e91d81c18ca8b253acb3c092c26dd02f60f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14a58cd821222e03d6898ec3a0e09b9c5dfa445f41a76e89ccf22e997754d19a092200a44e14a657fb027fddafdc22e2514957b26a55bf6e4df284ef516a4787", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe48ca6afed5ac1f23c37abecc1fb9fdb34f383739be7189a86b5075a2c7f6a16997ed39ea3402b48a51f0d517a9ab28d91c49056793264b9e243613d617669e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c1359aedefa88945a3e30695ea5e552c982fe37bc21b7c1736d62055d19c49111fe51864f9026d05ed0528655740f21dee5cdae6a3e576fbe4ce3268778c556"} 16:59:57 INFO - Running post-run listener: copy_logs_to_upload_dir 16:59:57 INFO - Copying logs to upload dir... 16:59:57 INFO - mkdir: /builds/slave/test/build/upload/logs 16:59:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.018600 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-21 16:59:57.733765) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 16:59:57.737312) ========= 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=52faae308a556c440401f8e5000001e8-1490139535.259856-988796537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/20bce4f356f25b70826b64ad1f88786d39fc434ead3ae85b011bd048fd5d982bf04753280b1ea613a3bd5d8161031e91d81c18ca8b253acb3c092c26dd02f60f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14a58cd821222e03d6898ec3a0e09b9c5dfa445f41a76e89ccf22e997754d19a092200a44e14a657fb027fddafdc22e2514957b26a55bf6e4df284ef516a4787", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe48ca6afed5ac1f23c37abecc1fb9fdb34f383739be7189a86b5075a2c7f6a16997ed39ea3402b48a51f0d517a9ab28d91c49056793264b9e243613d617669e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c1359aedefa88945a3e30695ea5e552c982fe37bc21b7c1736d62055d19c49111fe51864f9026d05ed0528655740f21dee5cdae6a3e576fbe4ce3268778c556"} build_url:https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/HuOhXf_MT0-mU7I9TNQtaQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/20bce4f356f25b70826b64ad1f88786d39fc434ead3ae85b011bd048fd5d982bf04753280b1ea613a3bd5d8161031e91d81c18ca8b253acb3c092c26dd02f60f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14a58cd821222e03d6898ec3a0e09b9c5dfa445f41a76e89ccf22e997754d19a092200a44e14a657fb027fddafdc22e2514957b26a55bf6e4df284ef516a4787", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe48ca6afed5ac1f23c37abecc1fb9fdb34f383739be7189a86b5075a2c7f6a16997ed39ea3402b48a51f0d517a9ab28d91c49056793264b9e243613d617669e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c1359aedefa88945a3e30695ea5e552c982fe37bc21b7c1736d62055d19c49111fe51864f9026d05ed0528655740f21dee5cdae6a3e576fbe4ce3268778c556"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 16:59:57.762054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:59:57.762374) ========= 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=52faae308a556c440401f8e5000001e8-1490139535.259856-988796537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 16:59:57.848190) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 16:59:57.848495) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 16:59:57.848783) ========= ========= Total master_lag: 0.15 =========