builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1475911457.99 results: failure (2) buildid: 20161007213111 builduid: 129b59c40476414c9624a80a4831c5a7 revision: a835589ae0c63a2d91be150d80b5fc600e44b447 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:17.991984) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:17.992372) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:17.992650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:18.010620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:18.010897) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-08 00:24:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2016-10-08 00:24:18 (70.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083887 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:18.107875) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:18.108184) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017371 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:18.157528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-08 00:24:18.157900) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a835589ae0c63a2d91be150d80b5fc600e44b447 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a835589ae0c63a2d91be150d80b5fc600e44b447 --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=934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-08 00:24:18,722 truncating revision to first 12 chars 2016-10-08 00:24:18,722 Setting DEBUG logging. 2016-10-08 00:24:18,722 attempt 1/10 2016-10-08 00:24:18,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a835589ae0c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-08 00:24:19,795 unpacking tar archive at: fx-team-a835589ae0c6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.383428 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-08 00:24:19.554662) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:19.554975) ========= script_repo_revision: a835589ae0c63a2d91be150d80b5fc600e44b447 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:19.555375) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:19.555695) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-08 00:24:19.568848) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 40 secs) (at 2016-10-08 00:24:19.569131) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:24:20 INFO - MultiFileLogger online at 20161008 00:24:20 in /builds/slave/test 00:24:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 00:24:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:24:20 INFO - {'append_to_log': False, 00:24:20 INFO - 'base_work_dir': '/builds/slave/test', 00:24:20 INFO - 'blob_upload_branch': 'Fx-Team', 00:24:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:24:20 INFO - 'branch': 'Fx-Team', 00:24:20 INFO - 'buildbot_json_path': 'buildprops.json', 00:24:20 INFO - 'config_files': ('talos/linux_config.py',), 00:24:20 INFO - 'default_actions': ('clobber', 00:24:20 INFO - 'read-buildbot-config', 00:24:20 INFO - 'download-and-extract', 00:24:20 INFO - 'populate-webroot', 00:24:20 INFO - 'create-virtualenv', 00:24:20 INFO - 'install', 00:24:20 INFO - 'run-tests'), 00:24:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:24:20 INFO - 'download_minidump_stackwalk': True, 00:24:20 INFO - 'download_symbols': 'ondemand', 00:24:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:24:20 INFO - 'tooltool.py': '/tools/tooltool.py', 00:24:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:24:20 INFO - '/tools/misc-python/virtualenv.py')}, 00:24:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:24:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:24:20 INFO - 'installer_path': 'installer.exe', 00:24:20 INFO - 'log_level': 'info', 00:24:20 INFO - 'log_name': 'talos', 00:24:20 INFO - 'log_to_console': True, 00:24:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:24:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:24:20 INFO - 'opt_config_files': (), 00:24:20 INFO - 'pip_index': False, 00:24:20 INFO - 'sps_profile': False, 00:24:20 INFO - 'sps_profile_interval': 0, 00:24:20 INFO - 'suite': 'dromaeojs', 00:24:20 INFO - 'system_bits': '32', 00:24:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:24:20 INFO - 'title': 'talos-linux64-ix-057', 00:24:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:24:20 INFO - 'use_talos_json': True, 00:24:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:24:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:24:20 INFO - 'work_dir': 'build'} 00:24:20 INFO - [mozharness: 2016-10-08 07:24:20.232875Z] Running clobber step. 00:24:20 INFO - Running pre-action listener: _resource_record_pre_action 00:24:20 INFO - Running main action method: clobber 00:24:20 INFO - rmtree: /builds/slave/test/build 00:24:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:24:21 INFO - Running post-action listener: _resource_record_post_action 00:24:21 INFO - [mozharness: 2016-10-08 07:24:21.214767Z] Finished clobber step (success) 00:24:21 INFO - [mozharness: 2016-10-08 07:24:21.214867Z] Running read-buildbot-config step. 00:24:21 INFO - Running pre-action listener: _resource_record_pre_action 00:24:21 INFO - Running main action method: read_buildbot_config 00:24:21 INFO - Using buildbot properties: 00:24:21 INFO - { 00:24:21 INFO - "project": "", 00:24:21 INFO - "product": "firefox", 00:24:21 INFO - "script_repo_revision": "production", 00:24:21 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 00:24:21 INFO - "repository": "", 00:24:21 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 00:24:21 INFO - "buildid": "20161007213111", 00:24:21 INFO - "pgo_build": "True", 00:24:21 INFO - "basedir": "/builds/slave/test", 00:24:21 INFO - "buildnumber": 1625, 00:24:21 INFO - "slavename": "talos-linux64-ix-057", 00:24:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 00:24:21 INFO - "platform": "ubuntu64_hw", 00:24:21 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 00:24:21 INFO - "branch": "fx-team", 00:24:21 INFO - "repo_path": "integration/fx-team", 00:24:21 INFO - "revision": "a835589ae0c63a2d91be150d80b5fc600e44b447", 00:24:21 INFO - "stage_platform": "linux64-pgo", 00:24:21 INFO - "builduid": "129b59c40476414c9624a80a4831c5a7", 00:24:21 INFO - "slavebuilddir": "test" 00:24:21 INFO - } 00:24:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 00:24:21 INFO - Running post-action listener: _resource_record_post_action 00:24:21 INFO - [mozharness: 2016-10-08 07:24:21.217314Z] Finished read-buildbot-config step (success) 00:24:21 INFO - [mozharness: 2016-10-08 07:24:21.217401Z] Running download-and-extract step. 00:24:21 INFO - Running pre-action listener: _resource_record_pre_action 00:24:21 INFO - Running main action method: download_and_extract 00:24:21 INFO - mkdir: /builds/slave/test/build/tests 00:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:21 INFO - trying https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 00:24:21 INFO - Downloading https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 00:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 00:24:21 INFO - Downloaded 1361 bytes. 00:24:21 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 00:24:21 INFO - Using the following test package requirements: 00:24:21 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 00:24:21 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:24:21 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:24:21 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'jsshell-linux-x86_64.zip'], 00:24:21 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:24:21 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 00:24:21 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:24:21 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:24:21 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:24:21 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:24:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:24:21 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:24:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 00:24:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:24:21 INFO - Fetch https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:24:22 INFO - Http code: 200 00:24:22 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 00:24:22 INFO - x-amz-cf-id: iq1rMKLfMTRsH7pMcx4LEQWVBAK4Itjqol6azxp1bcZvAY1YZOVUIg== 00:24:22 INFO - x-amz-version-id: QmSlQBt9H.KXgQoiQwp_O3PlgU406U1s 00:24:22 INFO - Expected file size: 22110972 00:24:22 INFO - Obtained file size: 22110972 00:24:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 00:24:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 00:24:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:24:26 INFO - Fetch https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:24:27 INFO - Http code: 200 00:24:27 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 00:24:27 INFO - x-amz-cf-id: ZAmPtbwPtS4YLuVmWcixOscVaH-HLZ720DxhfquoTNVbe7jIyY8r4A== 00:24:27 INFO - x-amz-version-id: QmSlQBt9H.KXgQoiQwp_O3PlgU406U1s 00:24:27 INFO - Expected file size: 22110972 00:24:27 INFO - Obtained file size: 22110972 00:24:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 00:24:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 00:24:32 INFO - Fetch https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 00:24:32 INFO - Http code: 200 00:24:32 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 00:24:32 INFO - x-amz-cf-id: R-JSlp7T-AnW3cG-7Zu5BYUxiRLoJT0JL-v7_rgLFmpMSryJXTDqbA== 00:24:32 INFO - x-amz-version-id: hF40.jjLN1fwo_VPX0Xt6wKiJ6Gl0Fis 00:24:32 INFO - Expected file size: 14409006 00:24:32 INFO - Obtained file size: 14409006 00:24:33 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')]} 00:24:33 INFO - trying https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:24:33 INFO - Downloading https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:24:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 00:24:34 INFO - Downloaded 59742939 bytes. 00:24:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:24:34 INFO - mkdir: /builds/slave/test/properties 00:24:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:24:34 INFO - Writing to file /builds/slave/test/properties/build_url 00:24:34 INFO - Contents: 00:24:34 INFO - build_url:https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:24:34 INFO - Running post-action listener: _resource_record_post_action 00:24:34 INFO - Running post-action listener: set_extra_try_arguments 00:24:34 INFO - [mozharness: 2016-10-08 07:24:34.859415Z] Finished download-and-extract step (success) 00:24:34 INFO - [mozharness: 2016-10-08 07:24:34.859642Z] Running populate-webroot step. 00:24:34 INFO - Running pre-action listener: _resource_record_pre_action 00:24:34 INFO - Running main action method: populate_webroot 00:24:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:24:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:24:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:24:34 INFO - '--tppagecycles', 00:24:34 INFO - '7'], 00:24:34 INFO - 'tests': ['tsvgm']}}, 00:24:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:24:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:24:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:24:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:24:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:24:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:24:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['damp', 'tps']}, 00:24:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'tests': ['damp', 'tps']}, 00:24:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['dromaeo_dom']}, 00:24:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:24:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['basic_compositor_video']}, 00:24:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:24:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['a11yr', 00:24:34 INFO - 'ts_paint', 00:24:34 INFO - 'tpaint', 00:24:34 INFO - 'sessionrestore', 00:24:34 INFO - 'sessionrestore_no_auto_restore', 00:24:34 INFO - 'tabpaint']}, 00:24:34 INFO - 'other-e10s': {'tests': ['a11yr', 00:24:34 INFO - 'ts_paint', 00:24:34 INFO - 'tpaint', 00:24:34 INFO - 'sessionrestore', 00:24:34 INFO - 'sessionrestore_no_auto_restore', 00:24:34 INFO - 'tabpaint']}, 00:24:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:24:34 INFO - 'ts_paint', 00:24:34 INFO - 'tpaint', 00:24:34 INFO - 'sessionrestore', 00:24:34 INFO - 'sessionrestore_no_auto_restore', 00:24:34 INFO - 'tabpaint']}, 00:24:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:24:34 INFO - 'ts_paint', 00:24:34 INFO - 'tpaint', 00:24:34 INFO - 'sessionrestore', 00:24:34 INFO - 'sessionrestore_no_auto_restore', 00:24:34 INFO - 'tabpaint']}, 00:24:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['a11yr', 00:24:34 INFO - 'ts_paint', 00:24:34 INFO - 'tpaint', 00:24:34 INFO - 'sessionrestore', 00:24:34 INFO - 'sessionrestore_no_auto_restore', 00:24:34 INFO - 'tabpaint']}, 00:24:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['a11yr', 00:24:34 INFO - 'ts_paint', 00:24:34 INFO - 'tpaint', 00:24:34 INFO - 'sessionrestore', 00:24:34 INFO - 'sessionrestore_no_auto_restore', 00:24:34 INFO - 'tabpaint']}, 00:24:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['tsvgx', 00:24:34 INFO - 'tsvgr_opacity', 00:24:34 INFO - 'tart', 00:24:34 INFO - 'tscrollx', 00:24:34 INFO - 'cart']}, 00:24:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:24:34 INFO - 'tsvgr_opacity', 00:24:34 INFO - 'tart', 00:24:34 INFO - 'tscrollx', 00:24:34 INFO - 'cart']}, 00:24:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'talos_options': ['--disable-e10s'], 00:24:34 INFO - 'tests': ['tp5o']}, 00:24:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'tests': ['tp5o']}, 00:24:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'talos_options': ['--disable-e10s', 00:24:34 INFO - '--xperf_path', 00:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:24:34 INFO - 'tests': ['tp5n']}, 00:24:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:34 INFO - 'talos_options': ['--xperf_path', 00:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:24:34 INFO - 'tests': ['tp5n']}}, 00:24:34 INFO - 'talos.zip': {'path': '', 00:24:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:24:34 INFO - Running post-action listener: _resource_record_post_action 00:24:34 INFO - [mozharness: 2016-10-08 07:24:34.878224Z] Finished populate-webroot step (success) 00:24:34 INFO - [mozharness: 2016-10-08 07:24:34.878312Z] Running create-virtualenv step. 00:24:34 INFO - Running pre-action listener: _resource_record_pre_action 00:24:34 INFO - Running main action method: create_virtualenv 00:24:34 INFO - Creating virtualenv /builds/slave/test/build/venv 00:24:34 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 00:24:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:24:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:24:34 INFO - Using real prefix '/usr' 00:24:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:24:35 INFO - Installing distribute.............................................................................................................................................................................................done. 00:24:37 INFO - Installing pip.................done. 00:24:37 INFO - Return code: 0 00:24:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:24:37 INFO - Reading from file tmpfile_stdout 00:24:37 INFO - Output received: 00:24:37 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) 00:24:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:24: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')]} 00:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24: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')]} 00:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24: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', 'pip>=1.5'] in /builds/slave/test/build 00:24: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 pip>=1.5 00:24:37 INFO - Using env: {'DISPLAY': ':0', 00:24:37 INFO - 'HOME': '/home/cltbld', 00:24:37 INFO - 'LANG': 'en_US.UTF-8', 00:24:37 INFO - 'LANGUAGE': 'en_US:en', 00:24:37 INFO - 'LOGNAME': 'cltbld', 00:24:37 INFO - 'MAIL': '/var/mail/cltbld', 00:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:37 INFO - 'MOZ_NO_REMOTE': '1', 00:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:37 INFO - 'NO_EM_RESTART': '1', 00:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:37 INFO - 'PWD': '/builds/slave/test', 00:24:37 INFO - 'SHELL': '/bin/bash', 00:24:37 INFO - 'SHLVL': '1', 00:24:37 INFO - 'TERM': 'linux', 00:24:37 INFO - 'TMOUT': '86400', 00:24:37 INFO - 'USER': 'cltbld', 00:24:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:37 INFO - '_': '/tools/buildbot/bin/python'} 00:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:37 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 00:24:37 INFO - Cleaning up... 00:24:37 INFO - Return code: 0 00:24:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:24: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')]} 00:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24: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')]} 00:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24: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', '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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24: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', 'psutil>=3.1.1'] in /builds/slave/test/build 00:24: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 psutil>=3.1.1 00:24:37 INFO - Using env: {'DISPLAY': ':0', 00:24:37 INFO - 'HOME': '/home/cltbld', 00:24:37 INFO - 'LANG': 'en_US.UTF-8', 00:24:37 INFO - 'LANGUAGE': 'en_US:en', 00:24:37 INFO - 'LOGNAME': 'cltbld', 00:24:37 INFO - 'MAIL': '/var/mail/cltbld', 00:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:37 INFO - 'MOZ_NO_REMOTE': '1', 00:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:37 INFO - 'NO_EM_RESTART': '1', 00:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:37 INFO - 'PWD': '/builds/slave/test', 00:24:37 INFO - 'SHELL': '/bin/bash', 00:24:37 INFO - 'SHLVL': '1', 00:24:37 INFO - 'TERM': 'linux', 00:24:37 INFO - 'TMOUT': '86400', 00:24:37 INFO - 'USER': 'cltbld', 00:24:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:37 INFO - '_': '/tools/buildbot/bin/python'} 00:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:38 INFO - Downloading/unpacking psutil>=3.1.1 00:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:24:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:24:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:24:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:24:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:24:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:24:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:24:40 INFO - Installing collected packages: psutil 00:24:40 INFO - Running setup.py install for psutil 00:24:40 INFO - building 'psutil._psutil_linux' extension 00:24:40 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 00:24:40 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 00:24:40 INFO - building 'psutil._psutil_posix' extension 00:24:40 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 00:24:40 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 00:24:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:24:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:24:40 INFO - Successfully installed psutil 00:24:40 INFO - Cleaning up... 00:24:40 INFO - Return code: 0 00:24:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:24: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')]} 00:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24: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')]} 00:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:40 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:40 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 00:24:40 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 00:24:40 INFO - Using env: {'DISPLAY': ':0', 00:24:40 INFO - 'HOME': '/home/cltbld', 00:24:40 INFO - 'LANG': 'en_US.UTF-8', 00:24:40 INFO - 'LANGUAGE': 'en_US:en', 00:24:40 INFO - 'LOGNAME': 'cltbld', 00:24:40 INFO - 'MAIL': '/var/mail/cltbld', 00:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:40 INFO - 'MOZ_NO_REMOTE': '1', 00:24:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:40 INFO - 'NO_EM_RESTART': '1', 00:24:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:40 INFO - 'PWD': '/builds/slave/test', 00:24:40 INFO - 'SHELL': '/bin/bash', 00:24:40 INFO - 'SHLVL': '1', 00:24:40 INFO - 'TERM': 'linux', 00:24:40 INFO - 'TMOUT': '86400', 00:24:40 INFO - 'USER': 'cltbld', 00:24:40 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:40 INFO - '_': '/tools/buildbot/bin/python'} 00:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:24:40 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 00:24:40 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 00:24:40 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 00:24:40 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 00:24:40 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 00:24:40 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 00:24:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:24:42 INFO - Installing collected packages: mozsystemmonitor 00:24:42 INFO - Running setup.py install for mozsystemmonitor 00:24:42 INFO - Successfully installed mozsystemmonitor 00:24:42 INFO - Cleaning up... 00:24:42 INFO - Return code: 0 00:24:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:42 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:42 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 00:24:42 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 00:24:42 INFO - Using env: {'DISPLAY': ':0', 00:24:42 INFO - 'HOME': '/home/cltbld', 00:24:42 INFO - 'LANG': 'en_US.UTF-8', 00:24:42 INFO - 'LANGUAGE': 'en_US:en', 00:24:42 INFO - 'LOGNAME': 'cltbld', 00:24:42 INFO - 'MAIL': '/var/mail/cltbld', 00:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:42 INFO - 'MOZ_NO_REMOTE': '1', 00:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:42 INFO - 'NO_EM_RESTART': '1', 00:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:42 INFO - 'PWD': '/builds/slave/test', 00:24:42 INFO - 'SHELL': '/bin/bash', 00:24:42 INFO - 'SHLVL': '1', 00:24:42 INFO - 'TERM': 'linux', 00:24:42 INFO - 'TMOUT': '86400', 00:24:42 INFO - 'USER': 'cltbld', 00:24:42 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:42 INFO - '_': '/tools/buildbot/bin/python'} 00:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:43 INFO - Downloading/unpacking jsonschema==2.5.1 00:24:43 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 00:24:43 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 00:24:43 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 00:24:43 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 00:24:43 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 00:24:43 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 00:24:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:24:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:24: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 00:24: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 00:24: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 00:24: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 00:24: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 00:24: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 00:24:45 INFO - Downloading functools32-3.2.3-2.tar.gz 00:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:24:45 INFO - warning: no files found matching '*.txt' 00:24:45 INFO - no previously-included directories found matching 'build' 00:24:45 INFO - no previously-included directories found matching 'dist' 00:24:45 INFO - no previously-included directories found matching '.git*' 00:24:45 INFO - Installing collected packages: jsonschema, functools32 00:24:45 INFO - Running setup.py install for functools32 00:24:45 INFO - warning: no files found matching '*.txt' 00:24:45 INFO - no previously-included directories found matching 'build' 00:24:45 INFO - no previously-included directories found matching 'dist' 00:24:45 INFO - no previously-included directories found matching '.git*' 00:24:45 INFO - Successfully installed jsonschema functools32 00:24:45 INFO - Cleaning up... 00:24:45 INFO - Return code: 0 00:24:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:24:45 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')]} 00:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:45 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')]} 00:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:45 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:45 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 00:24:45 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 00:24:45 INFO - Using env: {'DISPLAY': ':0', 00:24:45 INFO - 'HOME': '/home/cltbld', 00:24:45 INFO - 'LANG': 'en_US.UTF-8', 00:24:45 INFO - 'LANGUAGE': 'en_US:en', 00:24:45 INFO - 'LOGNAME': 'cltbld', 00:24:45 INFO - 'MAIL': '/var/mail/cltbld', 00:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:45 INFO - 'MOZ_NO_REMOTE': '1', 00:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:45 INFO - 'NO_EM_RESTART': '1', 00:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:45 INFO - 'PWD': '/builds/slave/test', 00:24:45 INFO - 'SHELL': '/bin/bash', 00:24:45 INFO - 'SHLVL': '1', 00:24:45 INFO - 'TERM': 'linux', 00:24:45 INFO - 'TMOUT': '86400', 00:24:45 INFO - 'USER': 'cltbld', 00:24:45 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:45 INFO - '_': '/tools/buildbot/bin/python'} 00:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:24:45 INFO - Cleaning up... 00:24:45 INFO - Return code: 0 00:24:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:24:45 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')]} 00:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:45 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')]} 00:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:45 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:45 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 00:24:45 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 00:24:45 INFO - Using env: {'DISPLAY': ':0', 00:24:45 INFO - 'HOME': '/home/cltbld', 00:24:45 INFO - 'LANG': 'en_US.UTF-8', 00:24:45 INFO - 'LANGUAGE': 'en_US:en', 00:24:45 INFO - 'LOGNAME': 'cltbld', 00:24:45 INFO - 'MAIL': '/var/mail/cltbld', 00:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:45 INFO - 'MOZ_NO_REMOTE': '1', 00:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:45 INFO - 'NO_EM_RESTART': '1', 00:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:45 INFO - 'PWD': '/builds/slave/test', 00:24:45 INFO - 'SHELL': '/bin/bash', 00:24:45 INFO - 'SHLVL': '1', 00:24:45 INFO - 'TERM': 'linux', 00:24:45 INFO - 'TMOUT': '86400', 00:24:45 INFO - 'USER': 'cltbld', 00:24:45 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:45 INFO - '_': '/tools/buildbot/bin/python'} 00:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:46 INFO - Downloading/unpacking blobuploader==1.2.4 00:24:46 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 00:24:46 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 00:24:46 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 00:24:46 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 00:24:46 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 00:24:46 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 00:24:48 INFO - Downloading blobuploader-1.2.4.tar.gz 00:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:24:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:24:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 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 00:24:48 INFO - Downloading docopt-0.6.1.tar.gz 00:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:24:48 INFO - Installing collected packages: blobuploader, requests, docopt 00:24:48 INFO - Running setup.py install for blobuploader 00:24:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:24:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:24:48 INFO - Running setup.py install for requests 00:24:49 INFO - Running setup.py install for docopt 00:24:49 INFO - Successfully installed blobuploader requests docopt 00:24:49 INFO - Cleaning up... 00:24:49 INFO - Return code: 0 00:24:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:49 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:49 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 00:24:49 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 00:24:49 INFO - Using env: {'DISPLAY': ':0', 00:24:49 INFO - 'HOME': '/home/cltbld', 00:24:49 INFO - 'LANG': 'en_US.UTF-8', 00:24:49 INFO - 'LANGUAGE': 'en_US:en', 00:24:49 INFO - 'LOGNAME': 'cltbld', 00:24:49 INFO - 'MAIL': '/var/mail/cltbld', 00:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:49 INFO - 'MOZ_NO_REMOTE': '1', 00:24:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:49 INFO - 'NO_EM_RESTART': '1', 00:24:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:49 INFO - 'PWD': '/builds/slave/test', 00:24:49 INFO - 'SHELL': '/bin/bash', 00:24:49 INFO - 'SHLVL': '1', 00:24:49 INFO - 'TERM': 'linux', 00:24:49 INFO - 'TMOUT': '86400', 00:24:49 INFO - 'USER': 'cltbld', 00:24:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:49 INFO - '_': '/tools/buildbot/bin/python'} 00:24:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:24:49 INFO - Running setup.py (path:/tmp/pip-rgh0nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:24:49 INFO - Running setup.py (path:/tmp/pip-N4t5Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:24:49 INFO - Running setup.py (path:/tmp/pip-Y9tfJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:24:49 INFO - Running setup.py (path:/tmp/pip-5dsw93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:24:49 INFO - Running setup.py (path:/tmp/pip-dPrf0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:24:50 INFO - Running setup.py (path:/tmp/pip-mCGmUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:24:50 INFO - Running setup.py (path:/tmp/pip-vUObc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:24:50 INFO - Running setup.py (path:/tmp/pip-9xP_8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:24:50 INFO - Running setup.py (path:/tmp/pip-uz8Z7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:24:50 INFO - Running setup.py (path:/tmp/pip-QURSuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:24:50 INFO - Running setup.py (path:/tmp/pip-gQPCuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:24:50 INFO - Running setup.py (path:/tmp/pip-X4M7NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:24:50 INFO - Running setup.py (path:/tmp/pip-9jlyc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:24:50 INFO - Running setup.py (path:/tmp/pip-x8otM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:24:50 INFO - Running setup.py (path:/tmp/pip-6RAhHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:24:50 INFO - Running setup.py (path:/tmp/pip-pTsXNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:24:50 INFO - Running setup.py (path:/tmp/pip-Vvbbkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:24:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:24:50 INFO - Running setup.py install for manifestparser 00:24:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:24:50 INFO - Running setup.py install for mozcrash 00:24:51 INFO - Running setup.py install for mozdebug 00:24:51 INFO - Running setup.py install for mozdevice 00:24:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Running setup.py install for mozfile 00:24:51 INFO - Running setup.py install for mozhttpd 00:24:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Running setup.py install for mozinfo 00:24:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Running setup.py install for mozInstall 00:24:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Running setup.py install for mozleak 00:24:51 INFO - Running setup.py install for mozlog 00:24:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:24:51 INFO - Running setup.py install for moznetwork 00:24:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:24:52 INFO - Running setup.py install for mozprocess 00:24:52 INFO - Running setup.py install for mozprofile 00:24:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:24:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:24:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:24:52 INFO - Running setup.py install for mozrunner 00:24:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:24:52 INFO - Running setup.py install for mozscreenshot 00:24:52 INFO - Running setup.py install for moztest 00:24:52 INFO - Running setup.py install for mozversion 00:24:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:24:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:24:52 INFO - Cleaning up... 00:24:52 INFO - Return code: 0 00:24:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:52 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:52 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 00:24:52 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 00:24:52 INFO - Using env: {'DISPLAY': ':0', 00:24:52 INFO - 'HOME': '/home/cltbld', 00:24:52 INFO - 'LANG': 'en_US.UTF-8', 00:24:52 INFO - 'LANGUAGE': 'en_US:en', 00:24:52 INFO - 'LOGNAME': 'cltbld', 00:24:52 INFO - 'MAIL': '/var/mail/cltbld', 00:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:52 INFO - 'MOZ_NO_REMOTE': '1', 00:24:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:52 INFO - 'NO_EM_RESTART': '1', 00:24:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:52 INFO - 'PWD': '/builds/slave/test', 00:24:52 INFO - 'SHELL': '/bin/bash', 00:24:52 INFO - 'SHLVL': '1', 00:24:52 INFO - 'TERM': 'linux', 00:24:52 INFO - 'TMOUT': '86400', 00:24:52 INFO - 'USER': 'cltbld', 00:24:52 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:52 INFO - '_': '/tools/buildbot/bin/python'} 00:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:24:52 INFO - Running setup.py (path:/tmp/pip-2uCgkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:24:52 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)) 00:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:24:52 INFO - Running setup.py (path:/tmp/pip-5Wh6Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:24:53 INFO - Running setup.py (path:/tmp/pip-x_OhGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:24:53 INFO - Running setup.py (path:/tmp/pip-N7uk3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:24:53 INFO - Running setup.py (path:/tmp/pip-7RRE67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:24:53 INFO - Running setup.py (path:/tmp/pip-sLi1XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:24:53 INFO - Running setup.py (path:/tmp/pip-YLdUks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:24:53 INFO - Running setup.py (path:/tmp/pip-BtCxLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:24:53 INFO - Running setup.py (path:/tmp/pip-RJICfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:24:53 INFO - Running setup.py (path:/tmp/pip-kG1AG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:24:53 INFO - Running setup.py (path:/tmp/pip-WAUdB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:24:53 INFO - Running setup.py (path:/tmp/pip-V8ZrxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:24:53 INFO - Running setup.py (path:/tmp/pip-jnunxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:24:53 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)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:24:53 INFO - Running setup.py (path:/tmp/pip-1U5PUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 00:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:24:53 INFO - Running setup.py (path:/tmp/pip-8qE90s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:24:54 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)) 00:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:24:54 INFO - Running setup.py (path:/tmp/pip-8cH0PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 00:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:24:54 INFO - Running setup.py (path:/tmp/pip-nCR3sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:24:54 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)) 00:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:24:54 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)) 00:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:24:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:24:54 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 00:24:54 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 00:24:54 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 00:24:54 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 00:24:54 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 00:24:54 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 00:24:56 INFO - Downloading blessings-1.6.tar.gz 00:24:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:24:56 INFO - Installing collected packages: blessings 00:24:56 INFO - Running setup.py install for blessings 00:24:56 INFO - Successfully installed blessings 00:24:56 INFO - Cleaning up... 00:24:56 INFO - Return code: 0 00:24:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:24:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:24:56 INFO - Reading from file tmpfile_stdout 00:24:56 INFO - Current package versions: 00:24:56 INFO - argparse == 1.2.1 00:24:56 INFO - blessings == 1.6 00:24:56 INFO - blobuploader == 1.2.4 00:24:56 INFO - docopt == 0.6.1 00:24:56 INFO - functools32 == 3.2.3-2 00:24:56 INFO - jsonschema == 2.5.1 00:24:56 INFO - manifestparser == 1.1 00:24:56 INFO - mozInstall == 1.12 00:24:56 INFO - mozcrash == 0.17 00:24:56 INFO - mozdebug == 0.1 00:24:56 INFO - mozdevice == 0.48 00:24:56 INFO - mozfile == 1.2 00:24:56 INFO - mozhttpd == 0.7 00:24:56 INFO - mozinfo == 0.9 00:24:56 INFO - mozleak == 0.1 00:24:56 INFO - mozlog == 3.4 00:24:56 INFO - moznetwork == 0.27 00:24:56 INFO - mozprocess == 0.23 00:24:56 INFO - mozprofile == 0.28 00:24:56 INFO - mozrunner == 6.12 00:24:56 INFO - mozscreenshot == 0.1 00:24:56 INFO - mozsystemmonitor == 0.3 00:24:56 INFO - moztest == 0.7 00:24:56 INFO - mozversion == 1.4 00:24:56 INFO - psutil == 3.1.1 00:24:56 INFO - requests == 1.2.3 00:24:56 INFO - wsgiref == 0.1.2 00:24:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:56 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:56 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 00:24:56 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 00:24:56 INFO - Using env: {'DISPLAY': ':0', 00:24:56 INFO - 'HOME': '/home/cltbld', 00:24:56 INFO - 'LANG': 'en_US.UTF-8', 00:24:56 INFO - 'LANGUAGE': 'en_US:en', 00:24:56 INFO - 'LOGNAME': 'cltbld', 00:24:56 INFO - 'MAIL': '/var/mail/cltbld', 00:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:56 INFO - 'MOZ_NO_REMOTE': '1', 00:24:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:56 INFO - 'NO_EM_RESTART': '1', 00:24:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:56 INFO - 'PWD': '/builds/slave/test', 00:24:56 INFO - 'SHELL': '/bin/bash', 00:24:56 INFO - 'SHLVL': '1', 00:24:56 INFO - 'TERM': 'linux', 00:24:56 INFO - 'TMOUT': '86400', 00:24:56 INFO - 'USER': 'cltbld', 00:24:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:56 INFO - '_': '/tools/buildbot/bin/python'} 00:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 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)) 00:24:56 INFO - Cleaning up... 00:24:56 INFO - Return code: 0 00:24:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:56 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 0x7fbaeabd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c3790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', '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 00:24:56 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 00:24:56 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 00:24:56 INFO - Using env: {'DISPLAY': ':0', 00:24:56 INFO - 'HOME': '/home/cltbld', 00:24:56 INFO - 'LANG': 'en_US.UTF-8', 00:24:56 INFO - 'LANGUAGE': 'en_US:en', 00:24:56 INFO - 'LOGNAME': 'cltbld', 00:24:56 INFO - 'MAIL': '/var/mail/cltbld', 00:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:56 INFO - 'MOZ_NO_REMOTE': '1', 00:24:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:24:56 INFO - 'NO_EM_RESTART': '1', 00:24:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:56 INFO - 'PWD': '/builds/slave/test', 00:24:56 INFO - 'SHELL': '/bin/bash', 00:24:56 INFO - 'SHLVL': '1', 00:24:56 INFO - 'TERM': 'linux', 00:24:56 INFO - 'TMOUT': '86400', 00:24:56 INFO - 'USER': 'cltbld', 00:24:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:56 INFO - '_': '/tools/buildbot/bin/python'} 00:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:24:56 INFO - Cleaning up... 00:24:56 INFO - Return code: 0 00:24:56 INFO - Running post-action listener: _resource_record_post_action 00:24:56 INFO - Running post-action listener: _start_resource_monitoring 00:24:57 INFO - Starting resource monitoring. 00:24:57 INFO - [mozharness: 2016-10-08 07:24:57.012107Z] Finished create-virtualenv step (success) 00:24:57 INFO - [mozharness: 2016-10-08 07:24:57.012635Z] Running install step. 00:24:57 INFO - Running pre-action listener: _resource_record_pre_action 00:24:57 INFO - Running main action method: install 00:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:24:57 INFO - Reading from file tmpfile_stdout 00:24:57 INFO - Detecting whether we're running mozinstall >=1.0... 00:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:24:57 INFO - Reading from file tmpfile_stdout 00:24:57 INFO - Output received: 00:24:57 INFO - Usage: mozinstall [options] installer 00:24:57 INFO - Options: 00:24:57 INFO - -h, --help show this help message and exit 00:24:57 INFO - -d DEST, --destination=DEST 00:24:57 INFO - Directory to install application into. [default: 00:24:57 INFO - "/builds/slave/test"] 00:24:57 INFO - --app=APP Application being installed. [default: firefox] 00:24:57 INFO - mkdir: /builds/slave/test/build/application 00:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 00:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 00:25:14 INFO - Reading from file tmpfile_stdout 00:25:14 INFO - Output received: 00:25:14 INFO - /builds/slave/test/build/application/firefox/firefox 00:25:14 INFO - Running post-action listener: _resource_record_post_action 00:25:14 INFO - [mozharness: 2016-10-08 07:25:14.974909Z] Finished install step (success) 00:25:14 INFO - [mozharness: 2016-10-08 07:25:14.975156Z] Running run-tests step. 00:25:14 INFO - Running pre-action listener: _resource_record_pre_action 00:25:14 INFO - Running main action method: run_tests 00:25:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:25:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:25:14 INFO - Python 2.7.3 00:25:14 INFO - Return code: 0 00:25:14 INFO - grabbing minidump binary from tooltool 00:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:25:14 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 0x25c0930>, '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 0x25c1830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c1cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:25:14 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 00:25:14 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 00:25:14 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 00:25:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:25:15 INFO - Return code: 0 00:25:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:25:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:25:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:25:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:25:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 00:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 00:25:15 INFO - Using env: {'DISPLAY': ':0', 00:25:15 INFO - 'HOME': '/home/cltbld', 00:25:15 INFO - 'LANG': 'en_US.UTF-8', 00:25:15 INFO - 'LANGUAGE': 'en_US:en', 00:25:15 INFO - 'LOGNAME': 'cltbld', 00:25:15 INFO - 'MAIL': '/var/mail/cltbld', 00:25:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:25:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:15 INFO - 'MOZ_NO_REMOTE': '1', 00:25:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:25:15 INFO - 'NO_EM_RESTART': '1', 00:25:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:15 INFO - 'PWD': '/builds/slave/test', 00:25:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:25:15 INFO - 'SHELL': '/bin/bash', 00:25:15 INFO - 'SHLVL': '1', 00:25:15 INFO - 'TERM': 'linux', 00:25:15 INFO - 'TMOUT': '86400', 00:25:15 INFO - 'USER': 'cltbld', 00:25:15 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765', 00:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:15 INFO - '_': '/tools/buildbot/bin/python'} 00:25:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 00:25:15 INFO - mozversion application_buildid: 20161007213111 00:25:15 INFO - mozversion application_changeset: a835589ae0c63a2d91be150d80b5fc600e44b447 00:25:15 INFO - mozversion application_display_name: Nightly 00:25:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:25:15 INFO - mozversion application_name: Firefox 00:25:15 INFO - mozversion application_remotingname: firefox 00:25:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:25:15 INFO - mozversion application_vendor: Mozilla 00:25:15 INFO - mozversion application_version: 52.0a1 00:25:15 INFO - mozversion platform_buildid: 20161007213111 00:25:15 INFO - mozversion platform_changeset: a835589ae0c63a2d91be150d80b5fc600e44b447 00:25:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:25:15 INFO - mozversion platform_version: 52.0a1 00:25:15 INFO - using testdate: 1475911515 00:25:15 INFO - actual date: 1475911515 00:25:15 INFO - starting webserver on 'localhost:41103' 00:25:15 INFO - SUITE-START | Running 2 tests 00:25:15 INFO - TEST-START | dromaeo_css 00:25:15 INFO - operating with platform_type : linux_ 00:25:15 INFO - Initialising browser for dromaeo_css test... 00:25:15 INFO - TEST-INFO | started process 23955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSvSePm/profile http://localhost:41103/getInfo.html) 00:25:19 INFO - PROCESS | 23955 | __metrics Screen width/height:1600/1200 00:25:19 INFO - PROCESS | 23955 | colorDepth:24 00:25:19 INFO - PROCESS | 23955 | Browser inner width/height: 1024/697 00:25:19 INFO - PROCESS | 23955 | __metrics 00:25:20 INFO - PROCESS | 23955 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:25:20 INFO - PROCESS | 23955 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:25:20 INFO - PROCESS | 23955 | console.error: PushService: 00:25:20 INFO - PROCESS | 23955 | stateChangeProcessEnqueue: Error transitioning state 00:25:20 INFO - PROCESS | 23955 | UnknownError 00:25:21 INFO - TEST-INFO | 23955: exit 0 00:25:21 INFO - Browser initialized. 00:25:21 INFO - Running cycle 1/1 for dromaeo_css test... 00:25:21 INFO - TEST-INFO | started process 24035 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSvSePm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:25:33 INFO - PROCESS | 24035 | RSS: Main: 173076480 00:25:33 INFO - PROCESS | 24035 | 00:26:25 INFO - PROCESS | 24035 | 1475911585169 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 00:28:35 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41103/tests/dromaeo/cssquery-ext.html) 00:28:36 INFO - PROCESS | 24035 | RSS: Main: 318472192 00:28:36 INFO - PROCESS | 24035 | 00:31:39 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-ext.html (next: http://localhost:41103/tests/dromaeo/cssquery-jquery.html) 00:31:39 INFO - PROCESS | 24035 | RSS: Main: 284311552 00:31:39 INFO - PROCESS | 24035 | 00:34:41 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41103/tests/dromaeo/cssquery-mootools.html) 00:34:42 INFO - PROCESS | 24035 | RSS: Main: 214904832 00:34:42 INFO - PROCESS | 24035 | 00:37:44 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41103/tests/dromaeo/cssquery-prototype.html) 00:37:45 INFO - PROCESS | 24035 | RSS: Main: 218099712 00:37:45 INFO - PROCESS | 24035 | 00:40:48 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41103/tests/dromaeo/cssquery-yui.html) 00:40:48 INFO - PROCESS | 24035 | RSS: Main: 216629248 00:40:48 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | Cycle 1(1): loaded http://localhost:41103/tests/dromaeo/cssquery-yui.html (next: http://localhost:41103/tests/dromaeo/cssquery-dojo.html) 00:43:51 INFO - PROCESS | 24035 | RSS: Main: 303480832 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | __start_tp_report 00:43:51 INFO - PROCESS | 24035 | _x_x_mozilla_page_load 00:43:51 INFO - PROCESS | 24035 | _x_x_mozilla_page_load_details 00:43:51 INFO - PROCESS | 24035 | |i|pagename|runs| 00:43:51 INFO - PROCESS | 24035 | |0;dojo.html;2989.4917863963124;3031.196732865791;3033.3175035616982;3055.22091866574;3081.9075427737175;5063.544281014708;5092.312537807395;5137.948620513794;5162.6644268122545;5201.115810312246;5082;5126.666766660164;5135.974320128404;5163.586913046956;5170.715610641413;4667.439907211131;4697.5772180503745;4703.576678098971;4713.128071306819;4721.645876559254;4694.812207511695;4769.046190761844;4771.165046116933;4810.87972800681;4817.156997525437;4746.976265118687;4791.880202994918;4829.3480380148585;4833.130036593412;4894.094592500381;4616.192166370905;4691.671582989192;4726.243800991842;4753.952460475385;4755.453122890843;4606.239970404879;4628.09752098341;4628.976855115737;4631.3516107744945;4663.48701642819;5045.041442125985;5051.873703157414;5058.3929928408825;5148.845534633967;5168.457311982263;5479.3424789025175;5480.479354461319;5484.588655850787;5487.314085739283;5490.3411590608985;5277.947220527784;5336.4129945705945;5434.483724046209;5530.253415788858;5534.75093620788;5166.8966620668125;5176.301199338116;5181.689098654093;5184.7926082956255;5187.766550505236;5646.2377579027125;5646.943530564724;5655.1799989001365;5655.915161272585;5723.856903577361;5169.612279079084;5217.373915130209;5218.869528261824;5278.604104692164;5281.762320695501;5328.653637513549;5336.359636843604;5431.1853222016225;5494.5054945055135;5496.065668836308;5446.972765136228;5469.316335458067;5506.03644362235;5534.0315444797;5536.4186760390385;5437.4562543745305;5468.972655136698;5471.06991811393;5472.7263636818;5495.587830912697;4192.454980852531;4240.236757383639;4275.166342563232;4299.204647140289;4351.26028575143;3793.886183414447;3839.9824046627664;3841.692664586826;3841.980790096032;3892.922141557153;5214.739263036833;5217.765200565984;5223.216517522326;5224.686518808883;5280.735963201824;5324.7870085196155;5475.863103422446;5540.445955404427;5543.722813859291;5553.694546799884;5058.8482345528955;5146.125158723026;5146.433892271847;5157.819476318386;5198.376194856642;4822.68652537584;4833.468318484964;4883.4139903211135;4886;4887;5698.62958907678;5704.572157088235;5704.942950570524;5706.80026199081;5708.514776244066;5333.306670132858;5401.405845357086;5522.558195344443;5530.446955304598;5588.30146231721;5626.493615574618;5682;5683.687397193029;5699.686517241592;5768.7404066817935;5349.679019258857;5399.568034557147;5403.027455058127;5403.8108666196495;5413.83758487246;5458.153986132156;5534.335879694462;5537.972310138423;5547.251121098761;5603.159526071122;5225.477452254896;5285.63000589955;5349.652272602269;5380.192971054373;5423.647462914898;5421.078416669096;5426.158945363475;5427.430119837518;5430.8099216527235;5480.862978425571;5354.7590358434745;5361.222622719606;5363.41002489718;5365.973170134124;5431.185322201702;5237.816676416307;5304.9204261936875;5338.3593968724;5366.275552800322;5440.945590544044;5275.630705850554;5279.102552565996;5285.814996475105;5333.226682131147;5364.919526207032 00:43:51 INFO - PROCESS | 24035 | |1;ext.html;542.964844860636;573.15459696947;597.1997523318752;597.6384287506058;600.9639421634704;34826.30347393051;42546.787266063664;43563.346549801776;43666.12667746647;47481.28778068332;45457.545424545824;45839;45859.77070114653;45908.081838363294;45972.03545308666;8128.187181281884;8318.168183181693;8967.641294348217;9130.360874738772;9153.313501487415;10615.52230149645;10889.891101088966;10893.183011274146;11212.383318917457;11233.157513186503;11059.889401105966;11168.832467512993;11183.776324473505;11185.888141118607;11189.384583847885;8316.916830831675;8531.402801803877;11016.11871050318;11088.33469991799;11089.611863584736;10354.119899808526;10702.518386672618;10729.302595331357;11025.503852326665;11028.393438360887;42697.57302426967;43051;43084.35373469401;43127.56872431298;43166.78416607897;3419.743519236067;3435.5362026126404;3447.517347571342;3494.5631796025496;3699.519062521882;30873.536896946564;31150.22124446884;31696.36607267842;31705.841470792497;31743.682563174305;4268.850590229327;4459.331100334944;4464.7321160730135;4499.932501012488;4605.171069207541;318453;320383;320594;320727;321061;57798.7110064447;58173;58249;58250.70874645685;58251.70874145687;62301.37698623046;62501;62595;62702;62830;16713.91643041777;16877.91561042187;16888.324467021426;16915.407960721317;16942.915285423493;10467.633632822966;10662.360258384522;12752.55366062202;13030.41363138642;13033.087683862039;18234.544136396697;18303.54241143982;18311;18339.816601834078;18451.81548184528;5338.252644629755;6112.205413296332;6196.318404975449;6196.566240363221;6248.656323902229;922.6724512797861;995.4077323992213;1032.7934413117407;1040.890706475809;1057.1595581512715;31739.625581906326;32090.679093208768;32142.678573214434;32348.514772278435;32510.674893250765;1062.5484169228048;1107.186218129674;1107.2858006585868;1112.3159257057014;1153.5097583526967;15766.352017791845;16611.584710382336;16755.413560525565;16898.49304520842;17244.913775431043;455487;456871;461148;463442;463892;59096.11355829666;59106.70446647825;59227.407725923054;59266.70366648139;59293.40706592965;1210.5399948019697;1257.9496820127092;1323.1135139456587;1329.0563699773077;1345.4685399267235;30049.849750751106;32313.51529727103;32530.674693252764;32766.67233327636;33630.159246019044;49407.50592494173;49562.50437495579;50041.24938126004;50044;50175;337199;341475;345793;348252;349074.52776416746;40464.39303410392;40916.18167636571;41994.58005419907;42018.789906050275;42031;45993.54006459893;46895;46965;47342.52657473381;47864;39508;40524.79737601411;40693.79653101716;40697.38953915753;42536.14927701367;39963;40540;41235.793821031904;41606.58393416027;41615.532285347355;38844.80577597094;40631.39052914149;40915;40981.795091025546;41060.38409423801;31024;35663.10842228965;35769;36150.27699445944;36383;27559.448811023267;27614.5857812137;27740.722592773815;28027;28048.579271311355 00:43:51 INFO - PROCESS | 24035 | |2;jquery.html;371.69149605827147;378.61989709549897;385.718425549349;392.27625031816297;418.04685317476145;14393.856061439383;17277.395291164812;18855.811441885577;19632.803671963276;19662.60674786505;15166.165860877647;17380.739288910678;18285.177167027483;18352.591795347078;19269.807301926976;5712.485876271135;5916.467517923386;5917.497012753921;5935.910961335583;5945.227120474332;5605.243292155547;5723.284589426322;5807.186993820868;5830.562707796911;5831.067029275317;5781.82654520365;5795.971020144914;5805.216295800076;5806.274215723035;5806.535477161838;5603.663780173202;5762.596618236725;5795.130730390433;5801.100829371454;5829.154772557956;5218.608604354688;5219.530242278213;5220.425753167148;5221.686698798084;5225.947740522584;22547.549049019093;23488.765112349;23620.409489762722;23682.171124010576;23866.40333991647;2190.4961858772413;2318.2002209237785;2329.813614910803;2423.430493833948;2929.4726949149144;9955.950220248926;13338.73322533544;14511.782323265159;14780.556583302518;15109.54671359861;1818.6544556534216;1864.766904136983;1867.3090956346102;1933.2653591635228;2011.9698204526944;80635.56504392256;83936.58031709863;84790;85149;89980;11028.779424411627;11057.723556911142;11079.72300692489;11172.217944743952;11436.485358158903;16132.516024519284;16210.027398356136;16239.188040597923;16242.675146497004;16250;6895.069165662571;7272.345488906024;7961.960190199012;8141.4708043978135;8427.452215605967;7909.5649739263745;7946.443748937519;8040.798980025429;8239.835203295901;8255.380846436541;4830.396200474941;4929.2359684248995;4938.234573641092;4966.726830024383;5058.873528161752;1836.8476423631575;1919.3378284491828;1987.672034114355;2040.795920407977;2041.806028427297;457.4167935881777;862.4437237981467;969.9078587534173;980.4852452462515;1017.5471914997755;11576.189666723418;12151.817722734166;12355.93822030884;12700.49816609189;13125.081244312998;458.532297057;463.34205428291904;474.0400688605601;886.9157430044136;918.8805455290903;10376.584936602452;10793.421039586801;10820.080293175168;10831.494623014696;10840;409140;441990;459028;462322;470312;89820.55089724509;90258;90343.54828225817;91184.54407728052;91344.54327728318;987.688878003441;1029.5161274201114;1047.2093569355088;1060.0830281806182;1063.2504084620325;16068.598285042968;16485.258163382437;16691.74962375541;16861.91569042147;16982.745258821375;37034.49168833053;38382.42426363551;38869.69607583387;38939.22121557609;39518.60481395149;54892.45107549033;54953.45046549643;55136;56735.71632141813;56843;8372.706955256537;8592.570371481526;8673.219410253107;8816.911830881609;9079.727608171765;9164.358494905293;9421.293402994666;9525.951695457386;9557.426554406758;10229;8582.914170858212;8627.568621569022;8663.566821659018;8966.551672416223;9430.858537122085;8571.485710857369;8750.081241469808;8760.561971901507;9081.137291957253;9152.95423522878;8716.302695784449;8879.511626860585;9081.909180908106;9132;9297.442153470813;12487.625371238877;12721.300328482022;12728.299943503196;12762.744745105232;13105.934470327587;12055.819162712742;12152.878471215528;12304.200226985216;12426.254424734545;12693.048021398617 00:43:51 INFO - PROCESS | 24035 | |3;mootools.html;1330.540963367639;1445.140141615739;1494.9252537373131;1610.24318570272;1756.9209385577649;7667.118281397634;8297.294729947955;8543.914560854397;8568.614412351444;8758.430702004382;8550.40147189697;8617.612207450664;8624.784380390493;8625.051244363114;8627.094155113717;2314.2941402872116;2346.5189636124555;2350.9529809403803;2351.0243249051623;2351.0830775997374;2278.943026424336;2304.873231972241;2335.9065637374483;2353.7175538935353;2356.705411823522;2307.5961706701346;2314.5833749925005;2324.9418764530938;2358.292512246328;2366.8343215974887;2261.7738226177416;2284.668723035167;2284.8971796269125;2290.6449500327394;2296.9425764355856;2265.275111964172;2269.863808171515;2271.034810205656;2271.5229801741652;2279.578278018572;8557.187067228604;8668.60991255395;8779.736607901774;8785.341099417505;8796.604152813139;5338.946610533883;5511.338639363262;5519.586031047647;5561.414996725955;5573.470520300565;3465.324261768956;3481.89554313371;3522.3307571561577;3524.2422879080864;3529;1081.5781845080426;1094.63876920616;1099;1103.0844399148768;1103.624767579019;12146.33195174265;12260.141790074698;12265.754684906342;12301.200421972542;12309.56916507918;4396.538363471854;4508.278675411918;4678.391809064799;4719.551642593948;4734.147853386424;9616;9636.421814691;9638.228941684649;9651.613935442643;9677.129058384779;5379.811706590251;5386.757595908193;5391.29913111293;5400.135978243462;5407;4228.471441069866;4419.9337009944875;4506.639468842484;4564.132814765184;5050.974745126251;3666.578343490533;3727.5154229949935;3734;3771.8114094295174;3801.4487899255005;1146.7247860513417;1153.8615366156116;1155.381870699172;1162.3665102519146;1164.4759858063735;809.8582748019073;931.4225180388203;933.2160984772688;943.9905600943903;944.0323668240109;5595.076812326002;5809.2738407699035;5945.672987985702;6025.6685882276015;6039.607425517327;938.8263171313329;955.2214944819949;956.583886009588;966.9516524173763;976.5703090640095;3816.580176180618;4105.794710264535;4107.075907920694;4112.465379500646;4159.064210552601;10846.18653600983;11269.54921803135;11281.32312061279;11291.3789741565;11298.491567879466;11226.55093796239;11236.269642473377;11241.100711943025;11256.606018789304;11268.38023908693;8186.754397368089;8196.508209507329;8294.336453083859;8311.376646751518;8319.500829950222;5764.250647415809;5788.9290481261105;5891.293044834647;6048.274207095177;6092.268927728701;9950.35322704022;10019.248556358449;10044.54799534008;10044.849327260243;10175.542100605342;9144.542772861463;9965.950170249102;10182.236332275257;10243.38539687621;10397.58409663605;6616.669166541557;7385.519941203805;7436.219196984455;7612.543247405173;7614.466987310835;8175.345972322319;8258.710945117133;8266.388054329347;8310.21052999964;8357.080721120794;7390.9260907390235;7515.6242187891485;7546.169921308542;7560.470767046474;7641.617919103911;7290.161631412456;7319.085114360705;7361.631918403951;7521.247875212435;7571.432142589218;6602.207735071822;6639.53523253387;6672.199336079701;6687.699053542699;6693.899591506034;4907.582855457326;5004.3994720633755;5007.574356179765;5028.34631497903;5266.210068489757;4938.506149385033;4990.3762029746285;5046.643981752094;5172.586193104467;5312.5218730314455 00:43:51 INFO - PROCESS | 24035 | |4;prototype.html;327.3616447926544;336.26693807499646;343.40247968534754;344.1739824421388;346.57544507977724;5230.241614965832;5309.681419114856;5318.521333080022;5341.145416733323;5365.7853685852615;5275.788968441258;5288.646552284225;5322.456487618586;5368.46315368463;5378.1663842104535;4074.633283004529;4109.856155034575;4132.214879172955;4156.293430116887;4162.958370416287;4115.32097203961;4163.333866581347;4176.227397931377;4183.560726123759;4183.811728472226;4090.141070375225;4092.6725861930972;4094.733842300241;4125.23683118624;4127.504699436072;3842.731008829369;3950.9012274693077;4006.379011253231;4098.9590104099025;4117.1765646870745;3675.5956844747056;3676.889693309205;3676.9816150919073;3680.576733675608;3696.8890933272046;5110.872228194287;5134.358205224347;5135.177012160667;5173.715445650488;5200.739963001873;4408.338749187616;4442.8667139985855;4598.149342371672;4646.697964632288;4657.417822772153;5436.320459942507;5447.291852059207;5447.6459030163305;5463.2078348639225;5526.696031718254;3993.1813978134414;4007.1785284016705;4007.3988901664693;4018.7789671568053;4038.535568409641;5499.285092937893;5613.37937030613;5684.4315568443235;5684.744186511617;5685.971570142165;4038.5759495252573;4071.816768245436;4078.4290199372113;4080.306672730843;4088.589436644353;4133.751974881457;4150.91698166035;4152.91694166115;4168.270552653274;4175.352820312856;4487.798049087799;4641.141388843075;4676.321933319649;4678.69588476748;4737;5527.50252477279;5535.723213839292;5541.390447050821;5545.390007099216;5546.972265138648;1390.9234992075533;1399.593408624326;1416.4475854416785;1417.2134465371612;1417.2913543228385;910.9316801239934;918.3433844801;921.4010892919522;974.8545459085547;985.4579981010415;5312.256284120226;5327.413984461706;5328;5328.573714102862;5331.173668081454;5642.463965923231;5643.984082865041;5655;5680.744366503517;5687.8339940312135;5218.243354713545;5224.399194092728;5271.499207575274;5338.97330513345;5339.759710813023;2884.2212602597183;2941.9994403805617;2944.748481895186;3006.2634654509784;3027.1826606816476;5839.445252700986;5843.912341314884;5847.532197424196;5867.237259156283;5884.588078834526;5559.610827242054;5721;5791.189233507313;5816.709164541756;5822.650640961556;5780.219670344533;5781.624194427349;5829.766809327664;5829.8251052468495;5853.9414605854245;5635.971820140873;5637.633553818989;5643.971780141073;5654.264945557051;5690.2887139107615;5419.3225846769155;5632.183333416713;5653.971730141323;5654.745536450787;5666.518345940642;5860.355360910382;5876.471117599436;5889.763149738603;5930.970345148246;5950.434708702666;3966.504186976628;4056.5537790843573;4059.817308221196;4070.3690927906223;4080.8571699990357;5694.544436444991;5695.772169113355;5704.286964129484;5712.200656793097;5733.741981610754;5595.944040559542;5602.607817452739;5622.8313150605545;5639.097744360883;5643.435656434488;5404.486573775485;5548.365820387652;5573.916391254215;5584.581156413204;5625.831225063254;5487.734797795305;5517.834464966058;5524.254225679482;5524.596800391842;5541.667499950016;5518.806841760519;5632.155176723524;5672.14917762339;5694.829155125353;5703.429657034263;5459.481349271813;5463.31708536433;5491.148871924858;5493;5515.448455154453 00:43:51 INFO - PROCESS | 24035 | |5;yui.html;714.742692630653;747.7420289999948;770.6917233106757;772.5171767645222;786.6027656033702;15326;16878.409255676055;16906.492805215832;16944.32222711093;17050.573735656613;14997.850021499782;15039.47361842333;15048.84951150488;15256.542303730877;15285.388584456636;655.0338251079643;669.4276393683404;674.9257581666013;772.2316295286198;792.3700657976893;673.9292374300701;678.0405725897849;780.7267456390247;802.0856223904724;805.8952336196286;834.5701963488808;839.0560619303284;839.6515446089866;839.7312859884839;841.4530555139178;831.5135645647304;832.7501749475164;833.2667252817482;833.3083540661297;833.5373867503563;516.648678898349;614.3150387318153;614.6066517428849;614.9200603921462;615.2248167309178;12251.326177060258;14657.633559161;15213.923930380388;15244.923775381163;15255.313510891925;13426.932865335708;14323.641908952255;14611.342489587994;14715;14754.631134221623;12753.234805911676;12964.80552791709;13070.477180912847;13139;13349;4446.4272436612255;4517.367568540407;4536;4541.364209010741;4583.541645835423;72739.63630181816;72850.63574682093;73746;74708.25291747121;75423.24576754218;11159.553617855358;11176.441177941071;11254.381009044417;11425.542978280775;11585.6524304271;21255.574888502142;21635.56728865414;21691.566168676538;21719.891400542896;22771.658425123638;8590.914090859136;8592.8711069334;8611.784705382415;8827.558622068871;8864.911350886538;4502.819887204475;4520.095980803787;4539.682222244412;4595.724256544551;4613.3771940787565;3206.198450387403;3274.50882367642;3300.570925779681;3300.818454984999;3307.4873394623874;566.0801198053164;577.7255803493391;580.7357652268208;584.611233529698;592.3543337761861;580.4572724502519;626.7399029402837;630.473202406715;639.4341008207676;648.6270394523168;8746.912530874737;8852.734417967471;8906.643734250687;8995.190432861074;9153.81692366149;646.1374065622447;651.3974573519475;675.4663815585633;686.4919959230234;715.9785206443815;3916.921661566753;4544.954550454519;4547.886302842456;4593.678442509059;4596.655250856199;74231.25768742243;116261;117654;126697;127066.36466817607;99291;101417.49291253496;105324;105815.47092264643;109452;92825;94836.52581737048;95280;96109.5194524023;98593;8437.282830959191;8635.179657932485;8860.867086993829;8899.466032038099;9005.909940900507;52409.47590524046;52788.47211527835;52998.470015300896;53170.73414633057;53504;69425.65287173532;75241;75418.6229068873;77506.92409986665;79333.60333198297;10792.298500597437;10890.727731806452;11004.614838480615;11500.769984600429;11516.769664606494;10848.891511084788;10928.180386470887;11090.445477726244;11353.205275630626;11985.880141198826;10770.946145269223;10955.397453140155;11130.443477826238;11450.37022963745;11706.473208705458;10223.795524089624;10600.310979786598;10972.286801357886;10998.285111467729;11204.327740335606;11526.308421494738;11666.941665291619;11804.232724872856;11915.76168476643;12271.509139634314;9439.480828554495;9505;9682.951585242028;9702.660406886007;9921.255905807238;8755.80610583987;9132.452052876848;9777;10045.146162576262;10089.09198172168 00:43:51 INFO - PROCESS | 24035 | __end_tp_report 00:43:51 INFO - PROCESS | 24035 | __start_cc_report 00:43:51 INFO - PROCESS | 24035 | _x_x_mozilla_cycle_collect,1388 00:43:51 INFO - PROCESS | 24035 | __end_cc_report 00:43:51 INFO - PROCESS | 24035 | __startTimestamp1475912631971__endTimestamp 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | ------- Summary: start ------- 00:43:51 INFO - PROCESS | 24035 | Number of tests: 6 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | [#0] dojo.html Cycles:165 Average:5116.19 Median:5279.10 stddev:552.19 (10.5%) stddev-sans-first:528.08 00:43:51 INFO - PROCESS | 24035 | Values: 2989.5 3031.2 3033.3 3055.2 3081.9 5063.5 5092.3 5137.9 5162.7 5201.1 5082.0 5126.7 5136.0 5163.6 5170.7 4667.4 4697.6 4703.6 4713.1 4721.6 4694.8 4769.0 4771.2 4810.9 4817.2 4747.0 4791.9 4829.3 4833.1 4894.1 4616.2 4691.7 4726.2 4754.0 4755.5 4606.2 4628.1 4629.0 4631.4 4663.5 5045.0 5051.9 5058.4 5148.8 5168.5 5479.3 5480.5 5484.6 5487.3 5490.3 5277.9 5336.4 5434.5 5530.3 5534.8 5166.9 5176.3 5181.7 5184.8 5187.8 5646.2 5646.9 5655.2 5655.9 5723.9 5169.6 5217.4 5218.9 5278.6 5281.8 5328.7 5336.4 5431.2 5494.5 5496.1 5447.0 5469.3 5506.0 5534.0 5536.4 5437.5 5469.0 5471.1 5472.7 5495.6 4192.5 4240.2 4275.2 4299.2 4351.3 3793.9 3840.0 3841.7 3842.0 3892.9 5214.7 5217.8 5223.2 5224.7 5280.7 5324.8 5475.9 5540.4 5543.7 5553.7 5058.8 5146.1 5146.4 5157.8 5198.4 4822.7 4833.5 4883.4 4886.0 4887.0 5698.6 5704.6 5704.9 5706.8 5708.5 5333.3 5401.4 5522.6 5530.4 5588.3 5626.5 5682.0 5683.7 5699.7 5768.7 5349.7 5399.6 5403.0 5403.8 5413.8 5458.2 5534.3 5538.0 5547.3 5603.2 5225.5 5285.6 5349.7 5380.2 5423.6 5421.1 5426.2 5427.4 5430.8 5480.9 5354.8 5361.2 5363.4 5366.0 5431.2 5237.8 5304.9 5338.4 5366.3 5440.9 5275.6 5279.1 5285.8 5333.2 5364.9 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | [#1] ext.html Cycles:180 Average:55240.86 Median:20041.26 stddev:99966.79 (498.8%) stddev-sans-first:100162.87 00:43:51 INFO - PROCESS | 24035 | Values: 543.0 573.2 597.2 597.6 601.0 34826.3 42546.8 43563.3 43666.1 47481.3 45457.5 45839.0 45859.8 45908.1 45972.0 8128.2 8318.2 8967.6 9130.4 9153.3 10615.5 10889.9 10893.2 11212.4 11233.2 11059.9 11168.8 11183.8 11185.9 11189.4 8316.9 8531.4 11016.1 11088.3 11089.6 10354.1 10702.5 10729.3 11025.5 11028.4 42697.6 43051.0 43084.4 43127.6 43166.8 3419.7 3435.5 3447.5 3494.6 3699.5 30873.5 31150.2 31696.4 31705.8 31743.7 4268.9 4459.3 4464.7 4499.9 4605.2 318453.0 320383.0 320594.0 320727.0 321061.0 57798.7 58173.0 58249.0 58250.7 58251.7 62301.4 62501.0 62595.0 62702.0 62830.0 16713.9 16877.9 16888.3 16915.4 16942.9 10467.6 10662.4 12752.6 13030.4 13033.1 18234.5 18303.5 18311.0 18339.8 18451.8 5338.3 6112.2 6196.3 6196.6 6248.7 922.7 995.4 1032.8 1040.9 1057.2 31739.6 32090.7 32142.7 32348.5 32510.7 1062.5 1107.2 1107.3 1112.3 1153.5 15766.4 16611.6 16755.4 16898.5 17244.9 455487.0 456871.0 461148.0 463442.0 463892.0 59096.1 59106.7 59227.4 59266.7 59293.4 1210.5 1257.9 1323.1 1329.1 1345.5 30049.8 32313.5 32530.7 32766.7 33630.2 49407.5 49562.5 50041.2 50044.0 50175.0 337199.0 341475.0 345793.0 348252.0 349074.5 40464.4 40916.2 41994.6 42018.8 42031.0 45993.5 46895.0 46965.0 47342.5 47864.0 39508.0 40524.8 40693.8 40697.4 42536.1 39963.0 40540.0 41235.8 41606.6 41615.5 38844.8 40631.4 40915.0 40981.8 41060.4 31024.0 35663.1 35769.0 36150.3 36383.0 27559.4 27614.6 27740.7 28027.0 28048.6 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | [#2] jquery.html Cycles:180 Average:27404.80 Median:231392.67 stddev:74371.89 (32.1%) stddev-sans-first:74552.83 00:43:51 INFO - PROCESS | 24035 | Values: 371.7 378.6 385.7 392.3 418.0 14393.9 17277.4 18855.8 19632.8 19662.6 15166.2 17380.7 18285.2 18352.6 19269.8 5712.5 5916.5 5917.5 5935.9 5945.2 5605.2 5723.3 5807.2 5830.6 5831.1 5781.8 5796.0 5805.2 5806.3 5806.5 5603.7 5762.6 5795.1 5801.1 5829.2 5218.6 5219.5 5220.4 5221.7 5225.9 22547.5 23488.8 23620.4 23682.2 23866.4 2190.5 2318.2 2329.8 2423.4 2929.5 9956.0 13338.7 14511.8 14780.6 15109.5 1818.7 1864.8 1867.3 1933.3 2012.0 80635.6 83936.6 84790.0 85149.0 89980.0 11028.8 11057.7 11079.7 11172.2 11436.5 16132.5 16210.0 16239.2 16242.7 16250.0 6895.1 7272.3 7962.0 8141.5 8427.5 7909.6 7946.4 8040.8 8239.8 8255.4 4830.4 4929.2 4938.2 4966.7 5058.9 1836.8 1919.3 1987.7 2040.8 2041.8 457.4 862.4 969.9 980.5 1017.5 11576.2 12151.8 12355.9 12700.5 13125.1 458.5 463.3 474.0 886.9 918.9 10376.6 10793.4 10820.1 10831.5 10840.0 409140.0 441990.0 459028.0 462322.0 470312.0 89820.6 90258.0 90343.5 91184.5 91344.5 987.7 1029.5 1047.2 1060.1 1063.3 16068.6 16485.3 16691.7 16861.9 16982.7 37034.5 38382.4 38869.7 38939.2 39518.6 54892.5 54953.5 55136.0 56735.7 56843.0 8372.7 8592.6 8673.2 8816.9 9079.7 9164.4 9421.3 9526.0 9557.4 10229.0 8582.9 8627.6 8663.6 8966.6 9430.9 8571.5 8750.1 8760.6 9081.1 9153.0 8716.3 8879.5 9081.9 9132.0 9297.4 12487.6 12721.3 12728.3 12762.7 13105.9 12055.8 12152.9 12304.2 12426.3 12693.0 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | [#3] mootools.html Cycles:180 Average:5723.55 Median:5017.96 stddev:3267.45 (65.1%) stddev-sans-first:3259.94 00:43:51 INFO - PROCESS | 24035 | Values: 1330.5 1445.1 1494.9 1610.2 1756.9 7667.1 8297.3 8543.9 8568.6 8758.4 8550.4 8617.6 8624.8 8625.1 8627.1 2314.3 2346.5 2351.0 2351.0 2351.1 2278.9 2304.9 2335.9 2353.7 2356.7 2307.6 2314.6 2324.9 2358.3 2366.8 2261.8 2284.7 2284.9 2290.6 2296.9 2265.3 2269.9 2271.0 2271.5 2279.6 8557.2 8668.6 8779.7 8785.3 8796.6 5338.9 5511.3 5519.6 5561.4 5573.5 3465.3 3481.9 3522.3 3524.2 3529.0 1081.6 1094.6 1099.0 1103.1 1103.6 12146.3 12260.1 12265.8 12301.2 12309.6 4396.5 4508.3 4678.4 4719.6 4734.1 9616.0 9636.4 9638.2 9651.6 9677.1 5379.8 5386.8 5391.3 5400.1 5407.0 4228.5 4419.9 4506.6 4564.1 5051.0 3666.6 3727.5 3734.0 3771.8 3801.4 1146.7 1153.9 1155.4 1162.4 1164.5 809.9 931.4 933.2 944.0 944.0 5595.1 5809.3 5945.7 6025.7 6039.6 938.8 955.2 956.6 967.0 976.6 3816.6 4105.8 4107.1 4112.5 4159.1 10846.2 11269.5 11281.3 11291.4 11298.5 11226.6 11236.3 11241.1 11256.6 11268.4 8186.8 8196.5 8294.3 8311.4 8319.5 5764.3 5788.9 5891.3 6048.3 6092.3 9950.4 10019.2 10044.5 10044.8 10175.5 9144.5 9966.0 10182.2 10243.4 10397.6 6616.7 7385.5 7436.2 7612.5 7614.5 8175.3 8258.7 8266.4 8310.2 8357.1 7390.9 7515.6 7546.2 7560.5 7641.6 7290.2 7319.1 7361.6 7521.2 7571.4 6602.2 6639.5 6672.2 6687.7 6693.9 4907.6 5004.4 5007.6 5028.3 5266.2 4938.5 4990.4 5046.6 5172.6 5312.5 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | [#4] prototype.html Cycles:180 Average:4666.87 Median:5340.45 stddev:1373.39 (25.7%) stddev-sans-first:1338.06 00:43:51 INFO - PROCESS | 24035 | Values: 327.4 336.3 343.4 344.2 346.6 5230.2 5309.7 5318.5 5341.1 5365.8 5275.8 5288.6 5322.5 5368.5 5378.2 4074.6 4109.9 4132.2 4156.3 4163.0 4115.3 4163.3 4176.2 4183.6 4183.8 4090.1 4092.7 4094.7 4125.2 4127.5 3842.7 3950.9 4006.4 4099.0 4117.2 3675.6 3676.9 3677.0 3680.6 3696.9 5110.9 5134.4 5135.2 5173.7 5200.7 4408.3 4442.9 4598.1 4646.7 4657.4 5436.3 5447.3 5447.6 5463.2 5526.7 3993.2 4007.2 4007.4 4018.8 4038.5 5499.3 5613.4 5684.4 5684.7 5686.0 4038.6 4071.8 4078.4 4080.3 4088.6 4133.8 4150.9 4152.9 4168.3 4175.4 4487.8 4641.1 4676.3 4678.7 4737.0 5527.5 5535.7 5541.4 5545.4 5547.0 1390.9 1399.6 1416.4 1417.2 1417.3 910.9 918.3 921.4 974.9 985.5 5312.3 5327.4 5328.0 5328.6 5331.2 5642.5 5644.0 5655.0 5680.7 5687.8 5218.2 5224.4 5271.5 5339.0 5339.8 2884.2 2942.0 2944.7 3006.3 3027.2 5839.4 5843.9 5847.5 5867.2 5884.6 5559.6 5721.0 5791.2 5816.7 5822.7 5780.2 5781.6 5829.8 5829.8 5853.9 5636.0 5637.6 5644.0 5654.3 5690.3 5419.3 5632.2 5654.0 5654.7 5666.5 5860.4 5876.5 5889.8 5931.0 5950.4 3966.5 4056.6 4059.8 4070.4 4080.9 5694.5 5695.8 5704.3 5712.2 5733.7 5595.9 5602.6 5622.8 5639.1 5643.4 5404.5 5548.4 5573.9 5584.6 5625.8 5487.7 5517.8 5524.3 5524.6 5541.7 5518.8 5632.2 5672.1 5694.8 5703.4 5459.5 5463.3 5491.1 5493.0 5515.4 00:43:51 INFO - PROCESS | 24035 | 00:43:51 INFO - PROCESS | 24035 | [#5] yui.html Cycles:180 Average:20729.01 Median:26463.06 stddev:30973.27 (117.0%) stddev-sans-first:31023.70 00:43:51 INFO - PROCESS | 24035 | Values: 714.7 747.7 770.7 772.5 786.6 15326.0 16878.4 16906.5 16944.3 17050.6 14997.9 15039.5 15048.8 15256.5 15285.4 655.0 669.4 674.9 772.2 792.4 673.9 678.0 780.7 802.1 805.9 834.6 839.1 839.7 839.7 841.5 831.5 832.8 833.3 833.3 833.5 516.6 614.3 614.6 614.9 615.2 12251.3 14657.6 15213.9 15244.9 15255.3 13426.9 14323.6 14611.3 14715.0 14754.6 12753.2 12964.8 13070.5 13139.0 13349.0 4446.4 4517.4 4536.0 4541.4 4583.5 72739.6 72850.6 73746.0 74708.3 75423.2 11159.6 11176.4 11254.4 11425.5 11585.7 21255.6 21635.6 21691.6 21719.9 22771.7 8590.9 8592.9 8611.8 8827.6 8864.9 4502.8 4520.1 4539.7 4595.7 4613.4 3206.2 3274.5 3300.6 3300.8 3307.5 566.1 577.7 580.7 584.6 592.4 580.5 626.7 630.5 639.4 648.6 8746.9 8852.7 8906.6 8995.2 9153.8 646.1 651.4 675.5 686.5 716.0 3916.9 4545.0 4547.9 4593.7 4596.7 74231.3 116261.0 117654.0 126697.0 127066.4 99291.0 101417.5 105324.0 105815.5 109452.0 92825.0 94836.5 95280.0 96109.5 98593.0 8437.3 8635.2 8860.9 8899.5 9005.9 52409.5 52788.5 52998.5 53170.7 53504.0 69425.7 75241.0 75418.6 77506.9 79333.6 10792.3 10890.7 11004.6 11500.8 11516.8 10848.9 10928.2 11090.4 11353.2 11985.9 10770.9 10955.4 11130.4 11450.4 11706.5 10223.8 10600.3 10972.3 10998.3 11204.3 11526.3 11666.9 11804.2 11915.8 12271.5 9439.5 9505.0 9683.0 9702.7 9921.3 8755.8 9132.5 9777.0 10045.1 10089.1 00:43:51 INFO - PROCESS | 24035 | -------- Summary: end -------- 00:43:51 INFO - PROCESS | 24035 | 00:43:52 INFO - TEST-INFO | 24035: exit 0 00:43:52 INFO - TEST-OK | dromaeo_css | took 1117552ms 00:43:52 INFO - TEST-START | kraken 00:43:52 INFO - operating with platform_type : linux_ 00:43:52 INFO - Initialising browser for kraken test... 00:43:52 INFO - TEST-INFO | started process 24338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjDLnhS/profile http://localhost:41103/getInfo.html) 00:43:53 INFO - PROCESS | 24338 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 00:43:53 INFO - TEST-INFO | 24338: exit 127 00:43:53 INFO - Could not find __metrics(.*)__metrics in browser output 00:43:53 INFO - Raw results:Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 00:43:53 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 00:43:53 ERROR - Traceback (most recent call last): 00:43:53 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 00:43:53 INFO - talos_results.add(mytest.runTest(browser_config, test)) 00:43:53 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 00:43:53 INFO - with FFSetup(browser_config, test_config) as setup: 00:43:53 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 165, in __enter__ 00:43:53 INFO - self._run_profile() 00:43:53 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 141, in _run_profile 00:43:53 INFO - raise TalosError("browser failed to close after being initialized") 00:43:53 INFO - TalosError: browser failed to close after being initialized 00:43:53 INFO - TEST-INFO took 277ms 00:43:53 INFO - SUITE-END | took 1117s 00:43:53 ERROR - Return code: 2 00:43:53 WARNING - setting return code to 2 00:43:53 ERROR - # TBPL FAILURE # 00:43:53 INFO - Running post-action listener: _resource_record_post_action 00:43:53 INFO - [mozharness: 2016-10-08 07:43:53.150873Z] Finished run-tests step (success) 00:43:53 INFO - Running post-run listener: _resource_record_post_run 00:43:53 INFO - Total resource usage - Wall time: 1136s; CPU: 13.0%; Read bytes: 4096; Write bytes: 349859840; Read time: 12; Write time: 341200 00:43:53 INFO - TinderboxPrint: CPU usage
13.2% 00:43:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 00:43:53 INFO - TinderboxPrint: I/O write bytes / time
349,859,840 / 341,200 00:43:53 INFO - TinderboxPrint: CPU idle
7,870.6 (86.8%) 00:43:53 INFO - TinderboxPrint: CPU user
1,156.2 (12.8%) 00:43:53 INFO - TinderboxPrint: Swap in / out
0 / 0 00:43:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 121176064; Read time: 0; Write time: 120372 00:43:53 INFO - run-tests - Wall time: 1118s; CPU: 13.0%; Read bytes: 4096; Write bytes: 228683776; Read time: 12; Write time: 220828 00:43:53 INFO - Running post-run listener: _upload_blobber_files 00:43:53 INFO - Blob upload gear active. 00:43:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:43:53 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 00:43:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:43:54 INFO - (blobuploader) - INFO - Open directory for files ... 00:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:43:54 INFO - (blobuploader) - INFO - Done attempting. 00:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:43:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:43:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:43:59 INFO - (blobuploader) - INFO - Done attempting. 00:43:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:43:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:43:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:44:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:44:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:44:00 INFO - (blobuploader) - INFO - Done attempting. 00:44:00 INFO - (blobuploader) - INFO - Iteration through files over. 00:44:00 INFO - Return code: 0 00:44:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:44:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:44:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14fd4258074efd91bafae69bf64da5fddb908d351a915a56e236d96df9769554b4dd77c97b2bbfe5935036b304a76d03ea8eeec1ca82e0d3d9b7795cf3a84e21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/101a828d71728e45f9ba9bdc9c268eafdb5ecdbc9c517228ee79034aed5354225c80edcc7cb1b373972ae81010e6b00e1481353a57dff46fbbee5f11b01a0be0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688cabcf945f63680d749c8cc898bb48a1883815649e1f36c9c3ea39a1c1527f516243d064910ecc11b368bad34ef6b386aac1804d7dffaeade9da1165e18729"} 00:44:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:44:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:44:00 INFO - Contents: 00:44:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14fd4258074efd91bafae69bf64da5fddb908d351a915a56e236d96df9769554b4dd77c97b2bbfe5935036b304a76d03ea8eeec1ca82e0d3d9b7795cf3a84e21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/101a828d71728e45f9ba9bdc9c268eafdb5ecdbc9c517228ee79034aed5354225c80edcc7cb1b373972ae81010e6b00e1481353a57dff46fbbee5f11b01a0be0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688cabcf945f63680d749c8cc898bb48a1883815649e1f36c9c3ea39a1c1527f516243d064910ecc11b368bad34ef6b386aac1804d7dffaeade9da1165e18729"} 00:44:00 INFO - Running post-run listener: copy_logs_to_upload_dir 00:44:00 INFO - Copying logs to upload dir... 00:44:00 INFO - mkdir: /builds/slave/test/build/upload/logs 00:44:00 INFO - Copying logs to upload dir... 00:44:00 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1180.809033 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 40 secs) (at 2016-10-08 00:44:00.390815) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-08 00:44:00.394037) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14fd4258074efd91bafae69bf64da5fddb908d351a915a56e236d96df9769554b4dd77c97b2bbfe5935036b304a76d03ea8eeec1ca82e0d3d9b7795cf3a84e21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/101a828d71728e45f9ba9bdc9c268eafdb5ecdbc9c517228ee79034aed5354225c80edcc7cb1b373972ae81010e6b00e1481353a57dff46fbbee5f11b01a0be0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688cabcf945f63680d749c8cc898bb48a1883815649e1f36c9c3ea39a1c1527f516243d064910ecc11b368bad34ef6b386aac1804d7dffaeade9da1165e18729"} build_url:https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/auhAanlRShSSQINRvDiZGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14fd4258074efd91bafae69bf64da5fddb908d351a915a56e236d96df9769554b4dd77c97b2bbfe5935036b304a76d03ea8eeec1ca82e0d3d9b7795cf3a84e21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/101a828d71728e45f9ba9bdc9c268eafdb5ecdbc9c517228ee79034aed5354225c80edcc7cb1b373972ae81010e6b00e1481353a57dff46fbbee5f11b01a0be0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688cabcf945f63680d749c8cc898bb48a1883815649e1f36c9c3ea39a1c1527f516243d064910ecc11b368bad34ef6b386aac1804d7dffaeade9da1165e18729"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-08 00:44:00.422027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 00:44:00.422704) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1475905804.652965-66728765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 00:44:00.506605) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-08 00:44:00.507255) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-08 00:44:00.507900) ========= ========= Total master_lag: 0.17 =========